builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1470460920.27 results: success (0) buildid: 20160805193003 builduid: def840ba1c0b426ca8dec567efe5b95a revision: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.273111) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.273540) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.273810) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.289502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.289767) ========= 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=a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-05 22:22:00-- 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% 222M=0s 2016-08-05 22:22:00 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117671 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.422405) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.422680) ========= 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=a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018617 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.472640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:00.472947) ========= 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=a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-05 22:22:00,969 truncating revision to first 12 chars 2016-08-05 22:22:00,969 Setting DEBUG logging. 2016-08-05 22:22:00,969 attempt 1/10 2016-08-05 22:22:00,969 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:22:01,280 unpacking tar archive at: mozilla-inbound-731d13870f6a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.652186 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:01.138446) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:01.138730) ========= script_repo_revision: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:01.139101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:01.139442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 22:22:01.148621) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-05 22:22:01.148919) ========= /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=a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:22:01 INFO - MultiFileLogger online at 20160805 22:22:01 in /builds/slave/test 22:22:01 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:22:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:22:01 INFO - {'append_to_log': False, 22:22:01 INFO - 'base_work_dir': '/builds/slave/test', 22:22:01 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:22:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:22:01 INFO - 'branch': 'Mozilla-Inbound', 22:22:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:22:01 INFO - 'config_files': ('talos/linux_config.py',), 22:22:01 INFO - 'default_actions': ('clobber', 22:22:01 INFO - 'read-buildbot-config', 22:22:01 INFO - 'download-and-extract', 22:22:01 INFO - 'populate-webroot', 22:22:01 INFO - 'create-virtualenv', 22:22:01 INFO - 'install', 22:22:01 INFO - 'run-tests'), 22:22:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:22:01 INFO - 'download_minidump_stackwalk': True, 22:22:01 INFO - 'download_symbols': 'ondemand', 22:22:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:22:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:22:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:22:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:22:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:22:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:22:01 INFO - 'installer_path': 'installer.exe', 22:22:01 INFO - 'log_level': 'info', 22:22:01 INFO - 'log_name': 'talos', 22:22:01 INFO - 'log_to_console': True, 22:22:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:22:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:22:01 INFO - 'opt_config_files': (), 22:22:01 INFO - 'pip_index': False, 22:22:01 INFO - 'sps_profile': False, 22:22:01 INFO - 'sps_profile_interval': 0, 22:22:01 INFO - 'suite': 'dromaeojs', 22:22:01 INFO - 'system_bits': '32', 22:22:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:22:01 INFO - 'title': 'talos-linux64-ix-002', 22:22:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:22:01 INFO - 'use_talos_json': True, 22:22:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:22:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:22:01 INFO - 'work_dir': 'build'} 22:22:01 INFO - [mozharness: 2016-08-06 05:22:01.737706Z] Running clobber step. 22:22:01 INFO - Running pre-action listener: _resource_record_pre_action 22:22:01 INFO - Running main action method: clobber 22:22:01 INFO - rmtree: /builds/slave/test/build 22:22:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:22:02 INFO - Running post-action listener: _resource_record_post_action 22:22:02 INFO - [mozharness: 2016-08-06 05:22:02.200045Z] Finished clobber step (success) 22:22:02 INFO - [mozharness: 2016-08-06 05:22:02.200146Z] Running read-buildbot-config step. 22:22:02 INFO - Running pre-action listener: _resource_record_pre_action 22:22:02 INFO - Running main action method: read_buildbot_config 22:22:02 INFO - Using buildbot properties: 22:22:02 INFO - { 22:22:02 INFO - "project": "", 22:22:02 INFO - "product": "firefox", 22:22:02 INFO - "script_repo_revision": "production", 22:22:02 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:22:02 INFO - "repository": "", 22:22:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:22:02 INFO - "buildid": "20160805193003", 22:22:02 INFO - "pgo_build": "True", 22:22:02 INFO - "basedir": "/builds/slave/test", 22:22:02 INFO - "buildnumber": 1358, 22:22:02 INFO - "slavename": "talos-linux64-ix-002", 22:22:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:22:02 INFO - "platform": "ubuntu64_hw", 22:22:02 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:22:02 INFO - "branch": "mozilla-inbound", 22:22:02 INFO - "repo_path": "integration/mozilla-inbound", 22:22:02 INFO - "revision": "731d13870f6a161f47ab8bffe5ee8e2ac92d5195", 22:22:02 INFO - "stage_platform": "linux64-pgo", 22:22:02 INFO - "builduid": "def840ba1c0b426ca8dec567efe5b95a", 22:22:02 INFO - "slavebuilddir": "test" 22:22:02 INFO - } 22:22:02 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:22:02 INFO - Running post-action listener: _resource_record_post_action 22:22:02 INFO - [mozharness: 2016-08-06 05:22:02.202585Z] Finished read-buildbot-config step (success) 22:22:02 INFO - [mozharness: 2016-08-06 05:22:02.202674Z] Running download-and-extract step. 22:22:02 INFO - Running pre-action listener: _resource_record_pre_action 22:22:02 INFO - Running main action method: download_and_extract 22:22:02 INFO - mkdir: /builds/slave/test/build/tests 22:22: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:22:02 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:22:02 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:22:02 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:22:02 INFO - Downloaded 1361 bytes. 22:22:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:22:02 INFO - Using the following test package requirements: 22:22:02 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:22:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:22:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:22:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'jsshell-linux-x86_64.zip'], 22:22:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:22:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:22:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:22:02 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:22:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:22:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:22:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:22: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:22: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:22: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:22: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:22:03 INFO - Downloaded 21827257 bytes. 22:22: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:22: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:22:04 INFO - Return code: 0 22:22:04 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:22: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:22:04 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:22:04 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:22: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.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:22:05 INFO - Downloaded 21827257 bytes. 22:22:05 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:22:05 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:22:07 INFO - Return code: 0 22:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:07 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:22:07 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:22:07 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:22:08 INFO - Downloaded 14413723 bytes. 22:22:08 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:22:08 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:22:08 INFO - Return code: 0 22:22:08 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:22:08 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:22:08 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:22:08 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:22:09 INFO - Downloaded 59969378 bytes. 22:22:09 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:22:09 INFO - mkdir: /builds/slave/test/properties 22:22:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:22:09 INFO - Writing to file /builds/slave/test/properties/build_url 22:22:09 INFO - Contents: 22:22:09 INFO - build_url:https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:22:10 INFO - Running post-action listener: _resource_record_post_action 22:22:10 INFO - Running post-action listener: set_extra_try_arguments 22:22:10 INFO - [mozharness: 2016-08-06 05:22:10.240099Z] Finished download-and-extract step (success) 22:22:10 INFO - [mozharness: 2016-08-06 05:22:10.240332Z] Running populate-webroot step. 22:22:10 INFO - Running pre-action listener: _resource_record_pre_action 22:22:10 INFO - Running main action method: populate_webroot 22:22:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:22:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:22:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:22:10 INFO - '--tppagecycles', 22:22:10 INFO - '7'], 22:22:10 INFO - 'tests': ['tsvgm']}}, 22:22:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:22:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:22:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:22:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:22:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:22:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:22:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['damp', 'tps']}, 22:22:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'tests': ['damp', 'tps']}, 22:22:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['dromaeo_dom']}, 22:22:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:22:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['basic_compositor_video']}, 22:22:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:22:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['a11yr', 22:22:10 INFO - 'ts_paint', 22:22:10 INFO - 'tpaint', 22:22:10 INFO - 'sessionrestore', 22:22:10 INFO - 'sessionrestore_no_auto_restore', 22:22:10 INFO - 'tabpaint']}, 22:22:10 INFO - 'other-e10s': {'tests': ['a11yr', 22:22:10 INFO - 'ts_paint', 22:22:10 INFO - 'tpaint', 22:22:10 INFO - 'sessionrestore', 22:22:10 INFO - 'sessionrestore_no_auto_restore', 22:22:10 INFO - 'tabpaint']}, 22:22:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:22:10 INFO - 'ts_paint', 22:22:10 INFO - 'tpaint', 22:22:10 INFO - 'sessionrestore', 22:22:10 INFO - 'sessionrestore_no_auto_restore', 22:22:10 INFO - 'tabpaint']}, 22:22:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:22:10 INFO - 'ts_paint', 22:22:10 INFO - 'tpaint', 22:22:10 INFO - 'sessionrestore', 22:22:10 INFO - 'sessionrestore_no_auto_restore', 22:22:10 INFO - 'tabpaint']}, 22:22:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['a11yr', 22:22:10 INFO - 'ts_paint', 22:22:10 INFO - 'tpaint', 22:22:10 INFO - 'sessionrestore', 22:22:10 INFO - 'sessionrestore_no_auto_restore', 22:22:10 INFO - 'tabpaint']}, 22:22:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['a11yr', 22:22:10 INFO - 'ts_paint', 22:22:10 INFO - 'tpaint', 22:22:10 INFO - 'sessionrestore', 22:22:10 INFO - 'sessionrestore_no_auto_restore', 22:22:10 INFO - 'tabpaint']}, 22:22:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['tsvgx', 22:22:10 INFO - 'tsvgr_opacity', 22:22:10 INFO - 'tart', 22:22:10 INFO - 'tscrollx', 22:22:10 INFO - 'cart']}, 22:22:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:22:10 INFO - 'tsvgr_opacity', 22:22:10 INFO - 'tart', 22:22:10 INFO - 'tscrollx', 22:22:10 INFO - 'cart']}, 22:22:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'talos_options': ['--disable-e10s'], 22:22:10 INFO - 'tests': ['tp5o']}, 22:22:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'tests': ['tp5o']}, 22:22:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'talos_options': ['--disable-e10s', 22:22:10 INFO - '--xperf_path', 22:22:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:22:10 INFO - 'tests': ['tp5n']}, 22:22:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:10 INFO - 'talos_options': ['--xperf_path', 22:22:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:22:10 INFO - 'tests': ['tp5n']}}, 22:22:10 INFO - 'talos.zip': {'path': '', 22:22:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:22:10 INFO - Running post-action listener: _resource_record_post_action 22:22:10 INFO - [mozharness: 2016-08-06 05:22:10.256528Z] Finished populate-webroot step (success) 22:22:10 INFO - [mozharness: 2016-08-06 05:22:10.256630Z] Running create-virtualenv step. 22:22:10 INFO - Running pre-action listener: _resource_record_pre_action 22:22:10 INFO - Running main action method: create_virtualenv 22:22:10 INFO - Creating virtualenv /builds/slave/test/build/venv 22:22:10 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:22:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:22:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:22:10 INFO - Using real prefix '/usr' 22:22:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:22:10 INFO - Installing distribute.............................................................................................................................................................................................done. 22:22:12 INFO - Installing pip.................done. 22:22:12 INFO - Return code: 0 22:22:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:22: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:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22: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:22: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:22:12 INFO - Using env: {'DISPLAY': ':0', 22:22:12 INFO - 'HOME': '/home/cltbld', 22:22:12 INFO - 'LANG': 'en_US.UTF-8', 22:22:12 INFO - 'LANGUAGE': 'en_US:en', 22:22:12 INFO - 'LOGNAME': 'cltbld', 22:22:12 INFO - 'MAIL': '/var/mail/cltbld', 22:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:12 INFO - 'MOZ_NO_REMOTE': '1', 22:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:12 INFO - 'NO_EM_RESTART': '1', 22:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:12 INFO - 'PWD': '/builds/slave/test', 22:22:12 INFO - 'SHELL': '/bin/bash', 22:22:12 INFO - 'SHLVL': '1', 22:22:12 INFO - 'TERM': 'linux', 22:22:12 INFO - 'TMOUT': '86400', 22:22:12 INFO - 'USER': 'cltbld', 22:22:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:12 INFO - '_': '/tools/buildbot/bin/python'} 22:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:13 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:22:13 INFO - Cleaning up... 22:22:13 INFO - Return code: 0 22:22:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:22:13 INFO - Using env: {'DISPLAY': ':0', 22:22:13 INFO - 'HOME': '/home/cltbld', 22:22:13 INFO - 'LANG': 'en_US.UTF-8', 22:22:13 INFO - 'LANGUAGE': 'en_US:en', 22:22:13 INFO - 'LOGNAME': 'cltbld', 22:22:13 INFO - 'MAIL': '/var/mail/cltbld', 22:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:13 INFO - 'MOZ_NO_REMOTE': '1', 22:22:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:13 INFO - 'NO_EM_RESTART': '1', 22:22:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:13 INFO - 'PWD': '/builds/slave/test', 22:22:13 INFO - 'SHELL': '/bin/bash', 22:22:13 INFO - 'SHLVL': '1', 22:22:13 INFO - 'TERM': 'linux', 22:22:13 INFO - 'TMOUT': '86400', 22:22:13 INFO - 'USER': 'cltbld', 22:22:13 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:13 INFO - '_': '/tools/buildbot/bin/python'} 22:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:13 INFO - Downloading/unpacking psutil>=3.1.1 22:22:13 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:22:13 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:22:13 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:22:13 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:22:13 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:22:13 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:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:22:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:22:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:22:15 INFO - Installing collected packages: psutil 22:22:15 INFO - Running setup.py install for psutil 22:22:15 INFO - building 'psutil._psutil_linux' extension 22:22:15 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:22:15 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:22:15 INFO - building 'psutil._psutil_posix' extension 22:22:15 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:22:15 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:22:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:22:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:22:15 INFO - Successfully installed psutil 22:22:15 INFO - Cleaning up... 22:22:15 INFO - Return code: 0 22:22:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:22: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:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22: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:22: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:22:15 INFO - Using env: {'DISPLAY': ':0', 22:22:15 INFO - 'HOME': '/home/cltbld', 22:22:15 INFO - 'LANG': 'en_US.UTF-8', 22:22:15 INFO - 'LANGUAGE': 'en_US:en', 22:22:15 INFO - 'LOGNAME': 'cltbld', 22:22:15 INFO - 'MAIL': '/var/mail/cltbld', 22:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:15 INFO - 'MOZ_NO_REMOTE': '1', 22:22:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:15 INFO - 'NO_EM_RESTART': '1', 22:22:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:15 INFO - 'PWD': '/builds/slave/test', 22:22:15 INFO - 'SHELL': '/bin/bash', 22:22:15 INFO - 'SHLVL': '1', 22:22:15 INFO - 'TERM': 'linux', 22:22:15 INFO - 'TMOUT': '86400', 22:22:15 INFO - 'USER': 'cltbld', 22:22:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:15 INFO - '_': '/tools/buildbot/bin/python'} 22:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:22:16 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:22:16 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:22:16 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:22:16 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:22:16 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:22:16 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:22:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:22:18 INFO - Installing collected packages: mozsystemmonitor 22:22:18 INFO - Running setup.py install for mozsystemmonitor 22:22:18 INFO - Successfully installed mozsystemmonitor 22:22:18 INFO - Cleaning up... 22:22:18 INFO - Return code: 0 22:22:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:22:18 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:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:18 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:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:18 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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22:18 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:22:18 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:22:18 INFO - Using env: {'DISPLAY': ':0', 22:22:18 INFO - 'HOME': '/home/cltbld', 22:22:18 INFO - 'LANG': 'en_US.UTF-8', 22:22:18 INFO - 'LANGUAGE': 'en_US:en', 22:22:18 INFO - 'LOGNAME': 'cltbld', 22:22:18 INFO - 'MAIL': '/var/mail/cltbld', 22:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:18 INFO - 'MOZ_NO_REMOTE': '1', 22:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:18 INFO - 'NO_EM_RESTART': '1', 22:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:18 INFO - 'PWD': '/builds/slave/test', 22:22:18 INFO - 'SHELL': '/bin/bash', 22:22:18 INFO - 'SHLVL': '1', 22:22:18 INFO - 'TERM': 'linux', 22:22:18 INFO - 'TMOUT': '86400', 22:22:18 INFO - 'USER': 'cltbld', 22:22:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:18 INFO - '_': '/tools/buildbot/bin/python'} 22:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:18 INFO - Downloading/unpacking jsonschema==2.5.1 22:22:18 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:22:18 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:22:18 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:22:18 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:22:18 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:22:18 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:22:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:22:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:22: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:22: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:22: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:22: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:22: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:22: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:22:20 INFO - Downloading functools32-3.2.3-2.tar.gz 22:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:22:20 INFO - warning: no files found matching '*.txt' 22:22:20 INFO - no previously-included directories found matching 'build' 22:22:20 INFO - no previously-included directories found matching 'dist' 22:22:20 INFO - no previously-included directories found matching '.git*' 22:22:20 INFO - Installing collected packages: jsonschema, functools32 22:22:20 INFO - Running setup.py install for functools32 22:22:20 INFO - warning: no files found matching '*.txt' 22:22:20 INFO - no previously-included directories found matching 'build' 22:22:20 INFO - no previously-included directories found matching 'dist' 22:22:20 INFO - no previously-included directories found matching '.git*' 22:22:20 INFO - Successfully installed jsonschema functools32 22:22:20 INFO - Cleaning up... 22:22:20 INFO - Return code: 0 22:22:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:22: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:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22: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:22: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:22:20 INFO - Using env: {'DISPLAY': ':0', 22:22:20 INFO - 'HOME': '/home/cltbld', 22:22:20 INFO - 'LANG': 'en_US.UTF-8', 22:22:20 INFO - 'LANGUAGE': 'en_US:en', 22:22:20 INFO - 'LOGNAME': 'cltbld', 22:22:20 INFO - 'MAIL': '/var/mail/cltbld', 22:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:20 INFO - 'MOZ_NO_REMOTE': '1', 22:22:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:20 INFO - 'NO_EM_RESTART': '1', 22:22:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:20 INFO - 'PWD': '/builds/slave/test', 22:22:20 INFO - 'SHELL': '/bin/bash', 22:22:20 INFO - 'SHLVL': '1', 22:22:20 INFO - 'TERM': 'linux', 22:22:20 INFO - 'TMOUT': '86400', 22:22:20 INFO - 'USER': 'cltbld', 22:22:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:20 INFO - '_': '/tools/buildbot/bin/python'} 22:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:22:20 INFO - Cleaning up... 22:22:20 INFO - Return code: 0 22:22:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:22: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:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22: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:22: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:22:20 INFO - Using env: {'DISPLAY': ':0', 22:22:20 INFO - 'HOME': '/home/cltbld', 22:22:20 INFO - 'LANG': 'en_US.UTF-8', 22:22:20 INFO - 'LANGUAGE': 'en_US:en', 22:22:20 INFO - 'LOGNAME': 'cltbld', 22:22:20 INFO - 'MAIL': '/var/mail/cltbld', 22:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:20 INFO - 'MOZ_NO_REMOTE': '1', 22:22:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:20 INFO - 'NO_EM_RESTART': '1', 22:22:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:20 INFO - 'PWD': '/builds/slave/test', 22:22:20 INFO - 'SHELL': '/bin/bash', 22:22:20 INFO - 'SHLVL': '1', 22:22:20 INFO - 'TERM': 'linux', 22:22:20 INFO - 'TMOUT': '86400', 22:22:20 INFO - 'USER': 'cltbld', 22:22:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:20 INFO - '_': '/tools/buildbot/bin/python'} 22:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:21 INFO - Downloading/unpacking blobuploader==1.2.4 22:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:22:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:22:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:22:23 INFO - Downloading blobuploader-1.2.4.tar.gz 22:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:22:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:22:23 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:22:23 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:22:23 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:22:23 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:22:23 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:22:23 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:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:22:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:22:23 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:22:23 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:22:23 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:22:23 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:22:23 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:22:23 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:22:23 INFO - Downloading docopt-0.6.1.tar.gz 22:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:22:23 INFO - Installing collected packages: blobuploader, requests, docopt 22:22:23 INFO - Running setup.py install for blobuploader 22:22:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:22:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:22:23 INFO - Running setup.py install for requests 22:22:24 INFO - Running setup.py install for docopt 22:22:24 INFO - Successfully installed blobuploader requests docopt 22:22:24 INFO - Cleaning up... 22:22:24 INFO - Return code: 0 22:22:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:24 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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22:24 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:22:24 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:22:24 INFO - Using env: {'DISPLAY': ':0', 22:22:24 INFO - 'HOME': '/home/cltbld', 22:22:24 INFO - 'LANG': 'en_US.UTF-8', 22:22:24 INFO - 'LANGUAGE': 'en_US:en', 22:22:24 INFO - 'LOGNAME': 'cltbld', 22:22:24 INFO - 'MAIL': '/var/mail/cltbld', 22:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:24 INFO - 'MOZ_NO_REMOTE': '1', 22:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:24 INFO - 'NO_EM_RESTART': '1', 22:22:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:24 INFO - 'PWD': '/builds/slave/test', 22:22:24 INFO - 'SHELL': '/bin/bash', 22:22:24 INFO - 'SHLVL': '1', 22:22:24 INFO - 'TERM': 'linux', 22:22:24 INFO - 'TMOUT': '86400', 22:22:24 INFO - 'USER': 'cltbld', 22:22:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:24 INFO - '_': '/tools/buildbot/bin/python'} 22:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:22:24 INFO - Running setup.py (path:/tmp/pip-9rmPZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:22:24 INFO - Running setup.py (path:/tmp/pip-L5sdhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:22:24 INFO - Running setup.py (path:/tmp/pip-lfdOfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:22:24 INFO - Running setup.py (path:/tmp/pip-__7l5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:22:24 INFO - Running setup.py (path:/tmp/pip-kYTLz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:22:25 INFO - Running setup.py (path:/tmp/pip-BrQcu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:22:25 INFO - Running setup.py (path:/tmp/pip-SbmZgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:22:25 INFO - Running setup.py (path:/tmp/pip-UmFj3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:22:25 INFO - Running setup.py (path:/tmp/pip-bgiNiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:22:25 INFO - Running setup.py (path:/tmp/pip-cSA_cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:22:25 INFO - Running setup.py (path:/tmp/pip-qXhIom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:22:25 INFO - Running setup.py (path:/tmp/pip-0BSNb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:22:25 INFO - Running setup.py (path:/tmp/pip-_bM4WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:22:25 INFO - Running setup.py (path:/tmp/pip-rIbBG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:22:25 INFO - Running setup.py (path:/tmp/pip-25Lb2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:22:25 INFO - Running setup.py (path:/tmp/pip-vjhTZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:22:25 INFO - Running setup.py (path:/tmp/pip-N6owo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:22:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:22:25 INFO - Running setup.py install for manifestparser 22:22:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:22:25 INFO - Running setup.py install for mozcrash 22:22:26 INFO - Running setup.py install for mozdebug 22:22:26 INFO - Running setup.py install for mozdevice 22:22:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Running setup.py install for mozfile 22:22:26 INFO - Running setup.py install for mozhttpd 22:22:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Running setup.py install for mozinfo 22:22:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Running setup.py install for mozInstall 22:22:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Running setup.py install for mozleak 22:22:26 INFO - Running setup.py install for mozlog 22:22:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Running setup.py install for moznetwork 22:22:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:22:26 INFO - Running setup.py install for mozprocess 22:22:27 INFO - Running setup.py install for mozprofile 22:22:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:22:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:22:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:22:27 INFO - Running setup.py install for mozrunner 22:22:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:22:27 INFO - Running setup.py install for mozscreenshot 22:22:27 INFO - Running setup.py install for moztest 22:22:27 INFO - Running setup.py install for mozversion 22:22:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:22:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:22:27 INFO - Cleaning up... 22:22:27 INFO - Return code: 0 22:22:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:22:27 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:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:27 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:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:27 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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22:27 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:22:27 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:22:27 INFO - Using env: {'DISPLAY': ':0', 22:22:27 INFO - 'HOME': '/home/cltbld', 22:22:27 INFO - 'LANG': 'en_US.UTF-8', 22:22:27 INFO - 'LANGUAGE': 'en_US:en', 22:22:27 INFO - 'LOGNAME': 'cltbld', 22:22:27 INFO - 'MAIL': '/var/mail/cltbld', 22:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:27 INFO - 'MOZ_NO_REMOTE': '1', 22:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:27 INFO - 'NO_EM_RESTART': '1', 22:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:27 INFO - 'PWD': '/builds/slave/test', 22:22:27 INFO - 'SHELL': '/bin/bash', 22:22:27 INFO - 'SHLVL': '1', 22:22:27 INFO - 'TERM': 'linux', 22:22:27 INFO - 'TMOUT': '86400', 22:22:27 INFO - 'USER': 'cltbld', 22:22:27 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:27 INFO - '_': '/tools/buildbot/bin/python'} 22:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:22:27 INFO - Running setup.py (path:/tmp/pip-HvC7dF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:22: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:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:22:27 INFO - Running setup.py (path:/tmp/pip-wk4hTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:22:28 INFO - Running setup.py (path:/tmp/pip-teGMAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:22:28 INFO - Running setup.py (path:/tmp/pip-6LPTsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:22:28 INFO - Running setup.py (path:/tmp/pip-NcA9BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:22:28 INFO - Running setup.py (path:/tmp/pip-Cwn4HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:22:28 INFO - Running setup.py (path:/tmp/pip-AUtrGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:22:28 INFO - Running setup.py (path:/tmp/pip-NFOwql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:22:28 INFO - Running setup.py (path:/tmp/pip-kzVPp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:22:28 INFO - Running setup.py (path:/tmp/pip-Q5Qxbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:22:28 INFO - Running setup.py (path:/tmp/pip-ONQAe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:22:28 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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:22:28 INFO - Running setup.py (path:/tmp/pip-T6MaM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:22: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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:22:28 INFO - Running setup.py (path:/tmp/pip-4I4A96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:22: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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:22:28 INFO - Running setup.py (path:/tmp/pip-bqn9Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:22: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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:22:28 INFO - Running setup.py (path:/tmp/pip-EYnuP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:22: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:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:22:28 INFO - Running setup.py (path:/tmp/pip-ReN0FH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:22:29 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:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:22:29 INFO - Running setup.py (path:/tmp/pip-c46Cip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:22:29 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:22:29 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:22:29 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:22:29 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:22:29 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:22:29 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:22:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:22:29 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:22:29 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:22:29 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:22:29 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:22:29 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:22:29 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:22:30 INFO - Downloading blessings-1.6.tar.gz 22:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:22:31 INFO - Installing collected packages: blessings 22:22:31 INFO - Running setup.py install for blessings 22:22:31 INFO - Successfully installed blessings 22:22:31 INFO - Cleaning up... 22:22:31 INFO - Return code: 0 22:22:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:22:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:22:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:22:31 INFO - Reading from file tmpfile_stdout 22:22:31 INFO - Current package versions: 22:22:31 INFO - argparse == 1.2.1 22:22:31 INFO - blessings == 1.6 22:22:31 INFO - blobuploader == 1.2.4 22:22:31 INFO - docopt == 0.6.1 22:22:31 INFO - functools32 == 3.2.3-2 22:22:31 INFO - jsonschema == 2.5.1 22:22:31 INFO - manifestparser == 1.1 22:22:31 INFO - mozInstall == 1.12 22:22:31 INFO - mozcrash == 0.17 22:22:31 INFO - mozdebug == 0.1 22:22:31 INFO - mozdevice == 0.48 22:22:31 INFO - mozfile == 1.2 22:22:31 INFO - mozhttpd == 0.7 22:22:31 INFO - mozinfo == 0.9 22:22:31 INFO - mozleak == 0.1 22:22:31 INFO - mozlog == 3.3 22:22:31 INFO - moznetwork == 0.27 22:22:31 INFO - mozprocess == 0.23 22:22:31 INFO - mozprofile == 0.28 22:22:31 INFO - mozrunner == 6.12 22:22:31 INFO - mozscreenshot == 0.1 22:22:31 INFO - mozsystemmonitor == 0.3 22:22:31 INFO - moztest == 0.7 22:22:31 INFO - mozversion == 1.4 22:22:31 INFO - psutil == 3.1.1 22:22:31 INFO - requests == 1.2.3 22:22:31 INFO - wsgiref == 0.1.2 22:22:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:22:31 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:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:31 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:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:31 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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22:31 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:22:31 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:22:31 INFO - Using env: {'DISPLAY': ':0', 22:22:31 INFO - 'HOME': '/home/cltbld', 22:22:31 INFO - 'LANG': 'en_US.UTF-8', 22:22:31 INFO - 'LANGUAGE': 'en_US:en', 22:22:31 INFO - 'LOGNAME': 'cltbld', 22:22:31 INFO - 'MAIL': '/var/mail/cltbld', 22:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:31 INFO - 'MOZ_NO_REMOTE': '1', 22:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:31 INFO - 'NO_EM_RESTART': '1', 22:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:31 INFO - 'PWD': '/builds/slave/test', 22:22:31 INFO - 'SHELL': '/bin/bash', 22:22:31 INFO - 'SHLVL': '1', 22:22:31 INFO - 'TERM': 'linux', 22:22:31 INFO - 'TMOUT': '86400', 22:22:31 INFO - 'USER': 'cltbld', 22:22:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:31 INFO - '_': '/tools/buildbot/bin/python'} 22:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 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:22:31 INFO - Cleaning up... 22:22:31 INFO - Return code: 0 22:22:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:22:31 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:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:31 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:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:31 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 0x1625030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', '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:22:31 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:22:31 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:22:31 INFO - Using env: {'DISPLAY': ':0', 22:22:31 INFO - 'HOME': '/home/cltbld', 22:22:31 INFO - 'LANG': 'en_US.UTF-8', 22:22:31 INFO - 'LANGUAGE': 'en_US:en', 22:22:31 INFO - 'LOGNAME': 'cltbld', 22:22:31 INFO - 'MAIL': '/var/mail/cltbld', 22:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:31 INFO - 'MOZ_NO_REMOTE': '1', 22:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:31 INFO - 'NO_EM_RESTART': '1', 22:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:31 INFO - 'PWD': '/builds/slave/test', 22:22:31 INFO - 'SHELL': '/bin/bash', 22:22:31 INFO - 'SHLVL': '1', 22:22:31 INFO - 'TERM': 'linux', 22:22:31 INFO - 'TMOUT': '86400', 22:22:31 INFO - 'USER': 'cltbld', 22:22:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:31 INFO - '_': '/tools/buildbot/bin/python'} 22:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:22:31 INFO - Cleaning up... 22:22:31 INFO - Return code: 0 22:22:31 INFO - Running post-action listener: _resource_record_post_action 22:22:31 INFO - Running post-action listener: _start_resource_monitoring 22:22:31 INFO - Starting resource monitoring. 22:22:31 INFO - [mozharness: 2016-08-06 05:22:31.897596Z] Finished create-virtualenv step (success) 22:22:31 INFO - [mozharness: 2016-08-06 05:22:31.898033Z] Running install step. 22:22:31 INFO - Running pre-action listener: _resource_record_pre_action 22:22:31 INFO - Running main action method: install 22:22:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:22:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:22:32 INFO - Reading from file tmpfile_stdout 22:22:32 INFO - Detecting whether we're running mozinstall >=1.0... 22:22:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:22:32 INFO - Reading from file tmpfile_stdout 22:22:32 INFO - Output received: 22:22:32 INFO - Usage: mozinstall [options] installer 22:22:32 INFO - Options: 22:22:32 INFO - -h, --help show this help message and exit 22:22:32 INFO - -d DEST, --destination=DEST 22:22:32 INFO - Directory to install application into. [default: 22:22:32 INFO - "/builds/slave/test"] 22:22:32 INFO - --app=APP Application being installed. [default: firefox] 22:22:32 INFO - mkdir: /builds/slave/test/build/application 22:22:32 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:22:32 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:22:49 INFO - Reading from file tmpfile_stdout 22:22:49 INFO - Output received: 22:22:49 INFO - /builds/slave/test/build/application/firefox/firefox 22:22:49 INFO - Running post-action listener: _resource_record_post_action 22:22:49 INFO - [mozharness: 2016-08-06 05:22:49.236210Z] Finished install step (success) 22:22:49 INFO - [mozharness: 2016-08-06 05:22:49.236458Z] Running run-tests step. 22:22:49 INFO - Running pre-action listener: _resource_record_pre_action 22:22:49 INFO - Running main action method: run_tests 22:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:22:49 INFO - Python 2.7.3 22:22:49 INFO - Return code: 0 22:22:49 INFO - grabbing minidump binary from tooltool 22:22:49 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:22:49 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 0x18e43b0>, '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 0x18e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18e5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:22:49 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:22:49 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:22:49 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:22:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:22:49 INFO - Return code: 0 22:22:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:22:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:22:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:22:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:22:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:22: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-002', '--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:22: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-002 --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:22:49 INFO - Using env: {'DISPLAY': ':0', 22:22:49 INFO - 'HOME': '/home/cltbld', 22:22:49 INFO - 'LANG': 'en_US.UTF-8', 22:22:49 INFO - 'LANGUAGE': 'en_US:en', 22:22:49 INFO - 'LOGNAME': 'cltbld', 22:22:49 INFO - 'MAIL': '/var/mail/cltbld', 22:22:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:22:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:49 INFO - 'MOZ_NO_REMOTE': '1', 22:22:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:49 INFO - 'NO_EM_RESTART': '1', 22:22:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:49 INFO - 'PWD': '/builds/slave/test', 22:22:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:22:49 INFO - 'SHELL': '/bin/bash', 22:22:49 INFO - 'SHLVL': '1', 22:22:49 INFO - 'TERM': 'linux', 22:22:49 INFO - 'TMOUT': '86400', 22:22:49 INFO - 'USER': 'cltbld', 22:22:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347', 22:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:49 INFO - '_': '/tools/buildbot/bin/python'} 22:22: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-002', '--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:22:49 INFO - mozversion application_buildid: 20160805193003 22:22:49 INFO - mozversion application_changeset: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 22:22:49 INFO - mozversion application_display_name: Nightly 22:22:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:22:49 INFO - mozversion application_name: Firefox 22:22:49 INFO - mozversion application_remotingname: firefox 22:22:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:22:49 INFO - mozversion application_vendor: Mozilla 22:22:49 INFO - mozversion application_version: 51.0a1 22:22:49 INFO - mozversion platform_buildid: 20160805193003 22:22:49 INFO - mozversion platform_changeset: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 22:22:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:22:49 INFO - mozversion platform_version: 51.0a1 22:22:49 INFO - using testdate: 1470460969 22:22:49 INFO - actual date: 1470460969 22:22:49 INFO - starting webserver on 'localhost:51868' 22:22:49 INFO - SUITE-START | Running 2 tests 22:22:49 INFO - TEST-START | dromaeo_css 22:22:49 INFO - operating with platform_type : linux_ 22:22:49 INFO - Initialising browser for dromaeo_css test... 22:22:49 INFO - TEST-INFO | started process 18017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGqmIcl/profile http://localhost:51868/getInfo.html) 22:22:53 INFO - PROCESS | 18017 | __metrics Screen width/height:1600/1200 22:22:53 INFO - PROCESS | 18017 | colorDepth:24 22:22:53 INFO - PROCESS | 18017 | Browser inner width/height: 1024/697 22:22:53 INFO - PROCESS | 18017 | __metrics 22:22:54 INFO - PROCESS | 18017 | 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:22:54 INFO - PROCESS | 18017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:22:55 INFO - TEST-INFO | 18017: exit 0 22:22:55 INFO - Browser initialized. 22:22:55 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:55 INFO - TEST-INFO | started process 18097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGqmIcl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:23:07 INFO - PROCESS | 18097 | RSS: Main: 156983296 22:23:07 INFO - PROCESS | 18097 | 22:23:58 INFO - PROCESS | 18097 | 1470461038917 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:26:08 INFO - PROCESS | 18097 | Cycle 1(1): loaded http://localhost:51868/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51868/tests/dromaeo/cssquery-ext.html) 22:26:09 INFO - PROCESS | 18097 | RSS: Main: 252309504 22:26:09 INFO - PROCESS | 18097 | 22:29:11 INFO - PROCESS | 18097 | Cycle 1(1): loaded http://localhost:51868/tests/dromaeo/cssquery-ext.html (next: http://localhost:51868/tests/dromaeo/cssquery-jquery.html) 22:29:12 INFO - PROCESS | 18097 | RSS: Main: 239890432 22:29:12 INFO - PROCESS | 18097 | 22:32:14 INFO - PROCESS | 18097 | Cycle 1(1): loaded http://localhost:51868/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51868/tests/dromaeo/cssquery-mootools.html) 22:32:14 INFO - PROCESS | 18097 | RSS: Main: 182857728 22:32:14 INFO - PROCESS | 18097 | 22:35:16 INFO - PROCESS | 18097 | Cycle 1(1): loaded http://localhost:51868/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51868/tests/dromaeo/cssquery-prototype.html) 22:35:17 INFO - PROCESS | 18097 | RSS: Main: 226652160 22:35:17 INFO - PROCESS | 18097 | 22:38:20 INFO - PROCESS | 18097 | Cycle 1(1): loaded http://localhost:51868/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51868/tests/dromaeo/cssquery-yui.html) 22:38:20 INFO - PROCESS | 18097 | RSS: Main: 186667008 22:38:20 INFO - PROCESS | 18097 | 22:41:23 INFO - PROCESS | 18097 | Cycle 1(1): loaded http://localhost:51868/tests/dromaeo/cssquery-yui.html (next: http://localhost:51868/tests/dromaeo/cssquery-dojo.html) 22:41:24 INFO - PROCESS | 18097 | RSS: Main: 274235392 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | __start_tp_report 22:41:24 INFO - PROCESS | 18097 | _x_x_mozilla_page_load 22:41:24 INFO - PROCESS | 18097 | _x_x_mozilla_page_load_details 22:41:24 INFO - PROCESS | 18097 | |i|pagename|runs| 22:41:24 INFO - PROCESS | 18097 | |0;dojo.html;3007.3684526249485;3036.6507851597066;3044.406340763551;3044.558539011845;3085.4908940024898;5264.736763161845;5293.735313234337;5310.946890531093;5369.328833895763;5411.945880541199;5354.089804733195;5357.973210133944;5363.6245462817615;5436.401995780461;5438.7280635968245;4862.951370486294;4937.061958227943;4945.23348880924;4952.702837829724;5058.721770302632;4991.101601711691;5028.49715028496;5036.294918711365;5054.9494505054845;5057.898842023157;4833.830815921444;4838.104950584153;4868.707877527359;4882.998985208059;5004.774785134678;4878.438979517366;4890.168671325883;4899.706017638953;4960.355153830015;4971.850844474636;4893.75531223441;4931.422099596346;4933.45731969483;4974.950250497485;4996.9500304996845;5291.947080529222;5347.946520534783;5412.269343638637;5413.621046526745;5469.371022332443;5733.3406658234;5778.219940308047;5827.125931110325;5833.300003999505;5850.531957443394;5878.088896221093;5883.735231914574;5885.117232415129;5893.499052580536;5909.852253693649;5277.736113194325;5342.866428339284;5382.381026181961;5386.326709161355;5486.259354987065;5917.171595976567;5924;5968.85077873048;5969.253843269591;5972.462478376967;5691.231683722727;5697.601167918288;5738.741756620962;5747.3677895431465;5749.1663708762835;5604.299462567179;5700.914486282709;5749.9712501437225;5767.596268261182;5822.912656310159;5770.6249093808765;5773.162891380726;5774.942250577525;5793.507551858055;5818.8254352369495;5746.741396637161;5786.305643322744;5794;5799.4780469757925;5801.622894511843;4346.630536404377;4351.325544540601;4355.847545335898;4414.889627759332;4440.733555986587;3919.2161567686576;3968.0873399117786;3980.5024371953505;3981.581933896899;4071.877843664695;5561.610687251934;5567.24842146305;5570.08093664549;5570.805021824298;5617.691026993554;5849.824505264849;5850.385709500526;5855.121731740273;5859.44335288147;5862.765489380377;5295.364556253275;5437.401885792559;5488.094464413407;5493.587980901369;5580.91628625571;4971.204607262821;5004.624653151028;5013.3983921928875;5015;5042.067217564762;5999.820005399845;6048.667323297261;6059.36376680451;6067.423594758579;6068.575199735976;5655.943440565542;5771.220885180447;5814.04068328738;5819.388964158702;5901.380355062656;6042.395760423922;6044.576879618385;6046.213992181164;6065.545084118797;6096.116063170904;5715.257016587983;5743.885122297447;5748.597598168255;5749.195112684311;5750.971245143747;5686.800961966312;5777.480026797609;5797.91303130445;5826.475617194473;5829.6793676346515;5668.3198016236665;5726.255586773859;5726.770929162787;5729.427057294237;5764.913526297193;5655.1517272409465;5697.287839020122;5716.971415142898;5718.142278658234;5719.342275638355;5447.836564903059;5538.280023596906;5571.526420254324;5594.300712410949;5654.095344744821;5700.942990570041;5717.8570535736935;5740.913886291625;5744.454276843693;5746.109353050284;5576;5582.944170558242;5589.301337332833;5591.04952158126;5591.524720398812 22:41:24 INFO - PROCESS | 18097 | |1;ext.html;599.8380437281934;600.7626987339999;601.8465291350708;621.9892674404093;633.5596760251625;32700.83649581752;42865.571344286545;43706;43765.56234437662;43774.12451750967;45146.774266128625;45546.316805247945;45564;45568.3164752529;45631.77184114083;8998.820023599525;9103.590338434753;9113.772155696128;9133.223675987549;9161.404508706946;8953.23897468716;9023.278137748997;9035.051319611444;9041.231495322905;9047.547622618875;9025.097490250955;9046.728598142034;9068.274538036942;9068.591913363915;9072.36493445459;8982.730518084436;9025.142611451902;9047.909520904805;9052.77368065797;9092.272618190595;8983.281337492985;9059.50172740499;9060.275177985812;9060.54697265134;9064.138906803842;42788.35817462741;43051;43178.35232471515;43227;43243.783781081205;3657.9634203658156;4130.91738165238;4131.669466442708;4182.121754431573;4300.462442194726;32815;32918.6708132918;33285.334293314;33493.49759753606;34463.6553634463;4023.517177938666;4209.97895010526;4218.472690913636;4232.132412855357;4250.3199488081755;375584;375724;376085;376135;377304.1134794336;45508;45944.54055459469;46095.539044609795;46096.539034609894;46107.76946115248;61043.389566104655;61089;61162;61165.694171528856;61213.38786612166;12892.48430062787;13568.864311356958;13808.930955345159;13896.444142234197;14257.714845703027;10388.220883433773;10391.116755075904;10396.428196449267;10417.531211095518;10450.581976720847;16304.51086467408;16396;16470.917645411697;16518.42185523525;16533.35466325382;5559.8888022239325;6563.158788678081;6722.327767223336;6931.09895713554;6931.896021559681;993.3791380387258;1047.3297089862494;1070.1920050361928;1073.2272763610188;1183.1126655008743;32984.67015329816;33005.08116372124;33154.668453315644;33155.66844331574;33449.83275083609;1113.3820729495208;1121.3047910295506;1127.22784892349;1147.5868687272575;1208.9637310880687;17151;17197.7420338695;17294.567635808955;17315.653686926442;17359;519567;525955;529108;531050;533386;57759.42240577625;57965.710171448874;58069.70965145147;58072.419275807544;58097.4190258092;1205.813098969661;1226.9509219631116;1352.4725357110735;1380.3581334679623;1383.508854356709;32150.35699286048;33443.498347525296;33790.831045845596;33945.66054339425;34550;50839.2374114396;54091.72954135361;56686.433135669766;56747;61931;354302;354827;357098;357505;357773;25745.613815793153;25834.35414114587;26023;28485.287867803472;29019.85490072536;48511.51488485211;48537.757311213216;48778.512214878814;48915.96097657632;49923.750381249316;34766.47850282197;35394.46908296429;35394.646053540164;35818.82090589635;35911.10222244465;40411.39382909318;40486;40911.1817763649;41042.38436423516;42567;34813.12967175841;35186.64813351936;35440.46839297361;35531.28937421289;35781.64218357887;34593.30813383769;36084.458733118496;37379.81310093432;37421;37740.43389349107;28730;28879.120879120877;29035.7096429033;29226.853865731387;29239.7076029237 22:41:24 INFO - PROCESS | 18097 | |2;jquery.html;375.1708723720577;377.8469719763495;388.4134956216114;388.5570449687356;421.77224298878605;16608;19037.714434283498;19170.520736981583;19939.700904486443;20073.899630501826;19539.609207815836;19747;20029.799702002976;20058.297959571417;20179.394618161477;5313.814016509423;5598.748056337475;5674.845872325809;5790.942090579104;5803;5731.312242530902;5807.245058142456;5813.186153938452;5813.73838177281;5816.621919575236;5604.41153678864;5747.166660834177;5752.769889204447;5768.653880767147;5780.595358324919;5716.428357164292;5739.081746920473;5745.195672605839;5749.568782341299;5757.539396848242;4905.2151655734915;5044.445111037783;5075.467075957008;5080.669756465818;5082.034413461431;23871.761282387302;23997.520049599087;24133.517329653485;24289;24311.14910978125;2343.2267351773876;2390.497995420964;2590.2876708905014;2621.3839747659285;2912.723291287324;14120.293985300694;14683.339249733788;15090.094594324266;15522;15564.766528502081;1881.5860510687626;1884.274554296592;1915.817997290255;1933.5262860599244;1944.562473443464;78087.21912780857;80804;81056.59471702663;82276;86227;11141.498632561554;11152.832707509393;11286.492107855167;11462.197646164856;11557.30656160633;15622;15622.140782257085;15630.45293414725;15637.60905977337;15660;7895.565743884026;7942.3646108311195;8010.79973000668;8233.34133269337;8254.711085112112;7758.573278469738;7770.883436748453;8020.919790802017;8055.677772889018;8057.879131813028;4765.332853400527;4831.8308859190465;4839.854804355875;4903.190973489405;4948.950510494921;1955.1983686688388;2001.4996250937265;2014.6776515757408;2020.2626041494748;2033.7152798608206;916.3677062826629;934.2946075712794;944.0087907696891;1037.6264544763878;1041.510490069666;19521.90239048824;19873.701894471593;20011.799882001284;20081.698774518394;20248.898755506423;977.2084611464736;982.9557669904872;987.437160818341;999.2905037423511;1012.0486742462062;10215.284930054973;10292.433916134685;10602.469876506144;10670.946645266724;10732.624358147428;467758;468496;470559;471399;474374;74993;75619.62190189013;85925.57037214773;88707.55646221856;90461;908.6955869783696;950.5627411390699;969.1568335548118;983.1741337276721;994.3288280410839;17254.482365529053;18116.72824907654;18342.72485912739;18345.357912473;18416.35542755997;43978.56021439745;44787.32819007652;45203.77398112988;45233.77383113195;47176.528234718586;58313;59201.40798591959;60133.39866601453;60608.696956514934;61493.38506614877;7875.991622136318;7944.165862584577;7993.92006079955;8033;8522.36082293818;12179.939100304797;12488;12530.937345313216;12617.558385456832;12719.87280127187;10915.454227288763;11158;11240;11619.941900290445;11875.881241187477;10610.8408373876;11030.393328367018;11080.889191107986;11389.601363952223;11476.827847582459;11240.662780116609;11273.098152147972;11358.148138889452;11358.488868001125;11565.595204167814;7935.801604959691;8089.393295502979;8284.668613255402;8371;8584.78538036529;8665.090165532529;10513.632022879163;10852.473872106752;11029.338239705643;11102.444877756241 22:41:24 INFO - PROCESS | 18097 | |3;mootools.html;1448.073233130796;1500.9324580393882;1643.9260233289501;1785.187739578492;1790.5255107396551;8466.872996905044;9167.312451566126;9168.770780730485;9173.449593024423;9174;7866.724664636738;8134.227248411406;8143.918560814405;8149.225823546768;8954.328425368107;2345.5308938212343;2420.225527831095;2420.479596886669;2446.2661201639526;2452.0682140786475;2333.3816538617193;2376.9049238030543;2379.6192609182535;2396.6884305040408;2399.1003373734848;2351.1183306260154;2379.4170428245106;2403.7476065013097;2405.0860672944254;2406.3743426709093;2308.7691230876944;2311.399036250587;2314.9768502315014;2315.1896836107394;2315.81473482121;2328.6739856420113;2388.9163879264142;2389.904403823845;2391.923634364543;2391.983407051996;8973.506457144855;9082.727518174466;9095.636174553076;9107.134822191882;9109.316801239867;5282.498162674539;5411.512963833235;5415.810446634388;5418.91871621922;5423.240746295522;3345.022663490317;3403.1832360233366;3548.733844961638;3552.342816578941;3562.2697347043795;1068.4550879051662;1079.4440862955505;1085.2403317677656;1108.7837871615038;1135.5174051028362;12107.515699372014;12142.392880356036;12148.21036632616;12222.633321000385;12226.694332641755;4551.226291530448;4659.697119687277;4736.7631618418945;4787.018661174451;4790.4251489821445;10217.846732299022;10251.38491690501;10273.229507786946;10277.972202779813;10280.434576098387;5314.97342513285;5320.095583750772;5324.041672498987;5325.360956685223;5326.041312563776;4834.274858771142;4872.829450969199;4889.731064791399;4897.657163998486;4956.405231372259;3791.8862434127022;3822.120912190212;3830.367989281793;3880.262750077476;3919.2553414851086;1193.9761204775855;1197.592818441734;1199.0827017331749;1210.9212901161397;1216.7262365967763;988.1057642833248;995.4027583450016;1000.6197644895038;1002.6190047781796;1014.0721240065405;5719.971400142973;5810.5061069809535;5921.851953701106;5928.881422371528;6082.2701275847185;479.6210993315312;487.51735781576696;487.81950678248563;489.8456986049454;492.91127597032164;3879.7090218233743;4069.064115253449;4075.816588253536;4157.233794862916;4169.145325208325;10960.177986651033;11439.37083460401;11445.771084578262;11471.139664525193;11471.827922581168;11357.091432685365;11363.715907102389;11378.203525753283;11391.97472227487;11437.656870293904;7931.127575966639;7979.162187970295;8055.597220139087;8075.3539716822515;8075.959620201861;5609.990201763721;5614.6069775116985;5648.2092507049865;5661.603687741983;5910.379410161871;10088;10369.377837329785;10404.68785936423;10406.895931040593;10562.8415573768;9925.3548519346;10073.899261007296;10884.51019704131;10935.671929842118;10981.560737570408;6665.733370665119;7870.645820937957;8135.7152499662225;8203;8223.876641850258;8157.959210203911;8251.587420628824;8261.297789687944;8466.407351485337;8494.57527123629;7315.597642129734;7386.741464048732;7513.549187048795;7772.92227077722;7988.6405111768945;7406.592637404994;7419.109706835215;7461.477696561188;7701.306882380389;7781.805454863674;7181.676824542804;7212;7289.307515785992;7304.233055529093;7310.6710198040155;5100.591952643854;5201.869953251047;5252.579793616425;5285.233641122093;5317.175837745156;5318.893622127458;5359.115745902039;5364.7585858635675;5475.890482190413;5532.28080349552 22:41:24 INFO - PROCESS | 18097 | |4;prototype.html;308.68822489285816;310.1811218383468;315.61967828766336;330.7106282003247;337.834461114054;5216.191490318997;5243.816466423676;5268.631195816294;5292.497212764782;5319.281896943911;4888.291197776321;4988.900221995558;5117.59059275258;5129.769160387782;5145.536901678848;4106.897327566812;4109.835606575748;4116.650084742785;4125.690573207006;4137.524184718751;4125.195586860564;4126.401671757593;4129.318662420697;4130.8554200602985;4140.37894315852;3881.514810648669;3951.6641085507767;4085.7140000199997;4106.384042393635;4130.752154870717;4023.637872591481;4025.4766880305374;4055.188962207541;4066.7763273019823;4110.527289361732;3615.204654975901;3626.274745050974;3627.4196128619296;3628.981855090734;3635.509206257148;4858.275312264166;5031.924521132186;5095.668781529189;5116.181410974264;5123.974380128113;4648.48866624671;4652;4656.253904785705;4656.790444429975;4661.254199328125;5406.297181366436;5428.212909128194;5433.320834895638;5500.779968801243;5523.944760552383;3749.9187950687356;3916.3733802591732;4002.779847108408;4018.417329487208;4021.4571032910476;5592.916106258409;5594;5600.467955544217;5606.439356064402;5612.831615051555;4144.295469770146;4144.8134833932545;4162.41726158338;4169;4175.582441755861;3925.509311336083;4224.556421575752;4225.9577404226175;4229.851955181553;4239.491261048633;4630.745309008037;4649.511801260886;4654.581087702123;4670.95329046712;4682.675300536589;5474.343078830566;5476.288082549243;5514.751836167382;5515.365732940683;5520.558355331564;1360.6122255157234;1421.644588852787;1433;1435.978460323096;1456.2936975566843;904.0507467159456;940.4921342474987;940.8400571902793;941.3975055964188;950.814591154732;4686.62506999446;4700.294955756595;4713.750856023156;4717.693349932243;4759.619230461555;4683.314006957493;4828.392667959262;4883.243097319921;4947.727874966911;4968.677035992721;4698.8120475181295;4710.976445117821;4712.340272361804;4720.457147428021;4729.290606409066;2595.1046888823325;2601.414681696603;2622.645942797698;2624.580067189279;2644.405008873027;5167.431582525919;5449.3460784706085;5822.679752613647;5888.411158884077;5894.616849904828;5740.827775166752;5754.324239228778;5754.510866576305;5757.827265182052;5769.836311383231;5749.453801888814;5866.706664666664;5890.410958904075;5923.526117910556;5928.703564821828;5531.695756733257;5536.390996990408;5563.833085007456;5565.688415392835;5574.804881829116;5499.780008799603;5507.118860982224;5553.777848885999;5568.693721845338;5581.860453488695;5634.971825140848;5781.479666830005;5822.475977162076;5826.621269617462;5890.793822216202;4040.9797951010055;4064.7764372959773;4082.7142100053898;4088.732492927202;4106.958930410658;5434.2935418395355;5528.668279903219;5534.142207957773;5537.695426751407;5684.232628595087;5003.814789492928;5337.786488540415;5339.529305951008;5363.383210930793;5365.463453654604;5334.146536554133;5345.919811202757;5383.623146379872;5425.728713564227;5443.646162999393;5273.393559740525;5336.412994570516;5381.219723140044;5425.565954723534;5439.755211015593;5425.593080518898;5461.371942226695;5477.507024367666;5498.367687715964;5506.2557460926955;5308.946910530845;5384.461553844584;5386.48828361305;5396.92375346049;5400.1899715043055 22:41:24 INFO - PROCESS | 18097 | |5;yui.html;718.421670555203;776.5806464509162;782.667366369293;810.5177419435441;827.7889138269741;15628.609284767886;16038.75941860873;16264.024158550495;16282;16579.41972030979;15225.619359516018;15291.770623440629;15314.617134571641;15344.693106137898;15355.616109597266;666.3037126203105;800.8758642410436;808.7492877208084;825.8637324841394;831.9376046796484;818.5866137600503;829.0300348592161;831.1065604475183;832.342449464922;832.8292699996487;886.6985225023491;886.8891388576428;887.6848718704864;888.537960260664;888.9599968001455;700.5376451541957;720.9134903811524;882.7793051737066;884.7788052986785;885.313881741649;529.4916879795401;627.2253766598247;641.2753588445074;643.1799455693981;648.1962366665348;14609.70780584393;14643.41426342945;14668.486602968846;14698.853011469855;14701.926490367587;14231.78652320216;14512.129272243594;14746.55760327181;14772.409103635842;15152.090874547452;13350;13518.324083795771;13586.86413135866;13629.318534073256;13803.792943105862;4464.352668863029;4492.84275050375;4516.864494065183;4637.814487420499;4708.270218116198;81942;81943.5902820488;81975.5901220496;82137;82652;11553.13351498624;11659.766804664028;12004.399780010965;12058.095642826647;12061.336626485452;22499.212527561456;22727.204547841076;22731;22766.430839228822;22767.544649106923;7943.165967573437;8760.255378292917;8825.735227943173;8900.510471924106;9037.864432033524;4598.425196850394;4820.4456487503685;4861.489543597942;4915.90168196634;4952.281919121707;3417.5557177567252;3533.6643018913164;3538.858445662196;3539.964600354015;3542.079059444563;488.63840757839654;604.0425924908981;606.8179546136141;607.5868409481595;610.0209164291338;633.5596760251581;651.221789960992;666.1772710702279;673.1988933169513;674.3559900295206;8986.415882967585;9349.53252337394;9838.114569688762;9966.856886540847;9999.750006249757;570.8829689913641;583.9591228614041;607.1560530862105;652.4649787174566;652.9412352888263;4700.2009658358165;4730.337752714623;4738.549837765407;4759.571638552547;4944.159492886218;73421.6328918352;89448.55275723709;125235;133680.33159834333;133973;60735;62396;64882.35117648858;78372.60813695895;93614;94180;97492.51253743687;100522.99477005283;101325;104085;8938.46369217849;9010.684626038057;9016.504092274725;9046.50244236547;9073.546322683971;54143.45856541384;55686.44313556812;55706.72146639241;56303.43696562982;57290.14064788948;69167.65416173088;70112.64943675249;70602.29397705957;72973.27026729664;73802.7859164226;12228.938855305667;12726.618201453972;13132.40904159296;13672.316384180553;13749.175049497062;14441.133531988115;14731.631709207355;14858.702825943637;15051.247437627855;15937.60155996063;13222.140560863512;13750.65623359424;14460.710785784015;14767.187804670846;14888.925555372154;13692.782196183689;13791.862081379059;14264.928675356558;15224.619384515032;15618;13340.26628535401;13908.860911390757;14007.719845603235;14039.157650540592;14302.213378264296;10878.681853147658;11553.653390398302;11814;12029.15795894279;12287.201331913397;11365.615229350615;11668.766624667629;11694.064474842162;12120.818187727367;12361 22:41:24 INFO - PROCESS | 18097 | __end_tp_report 22:41:24 INFO - PROCESS | 18097 | __start_cc_report 22:41:24 INFO - PROCESS | 18097 | _x_x_mozilla_cycle_collect,890 22:41:24 INFO - PROCESS | 18097 | __end_cc_report 22:41:24 INFO - PROCESS | 18097 | __startTimestamp1470462084003__endTimestamp 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | ------- Summary: start ------- 22:41:24 INFO - PROCESS | 18097 | Number of tests: 6 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | [#0] dojo.html Cycles:165 Average:5391.78 Median:5594.30 stddev:628.31 (11.2%) stddev-sans-first:601.75 22:41:24 INFO - PROCESS | 18097 | Values: 3007.4 3036.7 3044.4 3044.6 3085.5 5264.7 5293.7 5310.9 5369.3 5411.9 5354.1 5358.0 5363.6 5436.4 5438.7 4863.0 4937.1 4945.2 4952.7 5058.7 4991.1 5028.5 5036.3 5054.9 5057.9 4833.8 4838.1 4868.7 4883.0 5004.8 4878.4 4890.2 4899.7 4960.4 4971.9 4893.8 4931.4 4933.5 4975.0 4997.0 5291.9 5347.9 5412.3 5413.6 5469.4 5733.3 5778.2 5827.1 5833.3 5850.5 5878.1 5883.7 5885.1 5893.5 5909.9 5277.7 5342.9 5382.4 5386.3 5486.3 5917.2 5924.0 5968.9 5969.3 5972.5 5691.2 5697.6 5738.7 5747.4 5749.2 5604.3 5700.9 5750.0 5767.6 5822.9 5770.6 5773.2 5774.9 5793.5 5818.8 5746.7 5786.3 5794.0 5799.5 5801.6 4346.6 4351.3 4355.8 4414.9 4440.7 3919.2 3968.1 3980.5 3981.6 4071.9 5561.6 5567.2 5570.1 5570.8 5617.7 5849.8 5850.4 5855.1 5859.4 5862.8 5295.4 5437.4 5488.1 5493.6 5580.9 4971.2 5004.6 5013.4 5015.0 5042.1 5999.8 6048.7 6059.4 6067.4 6068.6 5655.9 5771.2 5814.0 5819.4 5901.4 6042.4 6044.6 6046.2 6065.5 6096.1 5715.3 5743.9 5748.6 5749.2 5751.0 5686.8 5777.5 5797.9 5826.5 5829.7 5668.3 5726.3 5726.8 5729.4 5764.9 5655.2 5697.3 5717.0 5718.1 5719.3 5447.8 5538.3 5571.5 5594.3 5654.1 5700.9 5717.9 5740.9 5744.5 5746.1 5576.0 5582.9 5589.3 5591.0 5591.5 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | [#1] ext.html Cycles:180 Average:57972.69 Median:22586.65 stddev:113145.23 (500.9%) stddev-sans-first:113380.63 22:41:24 INFO - PROCESS | 18097 | Values: 599.8 600.8 601.8 622.0 633.6 32700.8 42865.6 43706.0 43765.6 43774.1 45146.8 45546.3 45564.0 45568.3 45631.8 8998.8 9103.6 9113.8 9133.2 9161.4 8953.2 9023.3 9035.1 9041.2 9047.5 9025.1 9046.7 9068.3 9068.6 9072.4 8982.7 9025.1 9047.9 9052.8 9092.3 8983.3 9059.5 9060.3 9060.5 9064.1 42788.4 43051.0 43178.4 43227.0 43243.8 3658.0 4130.9 4131.7 4182.1 4300.5 32815.0 32918.7 33285.3 33493.5 34463.7 4023.5 4210.0 4218.5 4232.1 4250.3 375584.0 375724.0 376085.0 376135.0 377304.1 45508.0 45944.5 46095.5 46096.5 46107.8 61043.4 61089.0 61162.0 61165.7 61213.4 12892.5 13568.9 13808.9 13896.4 14257.7 10388.2 10391.1 10396.4 10417.5 10450.6 16304.5 16396.0 16470.9 16518.4 16533.4 5559.9 6563.2 6722.3 6931.1 6931.9 993.4 1047.3 1070.2 1073.2 1183.1 32984.7 33005.1 33154.7 33155.7 33449.8 1113.4 1121.3 1127.2 1147.6 1209.0 17151.0 17197.7 17294.6 17315.7 17359.0 519567.0 525955.0 529108.0 531050.0 533386.0 57759.4 57965.7 58069.7 58072.4 58097.4 1205.8 1227.0 1352.5 1380.4 1383.5 32150.4 33443.5 33790.8 33945.7 34550.0 50839.2 54091.7 56686.4 56747.0 61931.0 354302.0 354827.0 357098.0 357505.0 357773.0 25745.6 25834.4 26023.0 28485.3 29019.9 48511.5 48537.8 48778.5 48916.0 49923.8 34766.5 35394.5 35394.6 35818.8 35911.1 40411.4 40486.0 40911.2 41042.4 42567.0 34813.1 35186.6 35440.5 35531.3 35781.6 34593.3 36084.5 37379.8 37421.0 37740.4 28730.0 28879.1 29035.7 29226.9 29239.7 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | [#2] jquery.html Cycles:180 Average:28493.92 Median:405.16 stddev:77554.42 (19141.5%) stddev-sans-first:77743.25 22:41:24 INFO - PROCESS | 18097 | Values: 375.2 377.8 388.4 388.6 421.8 16608.0 19037.7 19170.5 19939.7 20073.9 19539.6 19747.0 20029.8 20058.3 20179.4 5313.8 5598.7 5674.8 5790.9 5803.0 5731.3 5807.2 5813.2 5813.7 5816.6 5604.4 5747.2 5752.8 5768.7 5780.6 5716.4 5739.1 5745.2 5749.6 5757.5 4905.2 5044.4 5075.5 5080.7 5082.0 23871.8 23997.5 24133.5 24289.0 24311.1 2343.2 2390.5 2590.3 2621.4 2912.7 14120.3 14683.3 15090.1 15522.0 15564.8 1881.6 1884.3 1915.8 1933.5 1944.6 78087.2 80804.0 81056.6 82276.0 86227.0 11141.5 11152.8 11286.5 11462.2 11557.3 15622.0 15622.1 15630.5 15637.6 15660.0 7895.6 7942.4 8010.8 8233.3 8254.7 7758.6 7770.9 8020.9 8055.7 8057.9 4765.3 4831.8 4839.9 4903.2 4949.0 1955.2 2001.5 2014.7 2020.3 2033.7 916.4 934.3 944.0 1037.6 1041.5 19521.9 19873.7 20011.8 20081.7 20248.9 977.2 983.0 987.4 999.3 1012.0 10215.3 10292.4 10602.5 10670.9 10732.6 467758.0 468496.0 470559.0 471399.0 474374.0 74993.0 75619.6 85925.6 88707.6 90461.0 908.7 950.6 969.2 983.2 994.3 17254.5 18116.7 18342.7 18345.4 18416.4 43978.6 44787.3 45203.8 45233.8 47176.5 58313.0 59201.4 60133.4 60608.7 61493.4 7876.0 7944.2 7993.9 8033.0 8522.4 12179.9 12488.0 12530.9 12617.6 12719.9 10915.5 11158.0 11240.0 11619.9 11875.9 10610.8 11030.4 11080.9 11389.6 11476.8 11240.7 11273.1 11358.1 11358.5 11565.6 7935.8 8089.4 8284.7 8371.0 8584.8 8665.1 10513.6 10852.5 11029.3 11102.4 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | [#3] mootools.html Cycles:180 Average:5840.54 Median:2633.32 stddev:3357.95 (127.5%) stddev-sans-first:3351.14 22:41:24 INFO - PROCESS | 18097 | Values: 1448.1 1500.9 1643.9 1785.2 1790.5 8466.9 9167.3 9168.8 9173.4 9174.0 7866.7 8134.2 8143.9 8149.2 8954.3 2345.5 2420.2 2420.5 2446.3 2452.1 2333.4 2376.9 2379.6 2396.7 2399.1 2351.1 2379.4 2403.7 2405.1 2406.4 2308.8 2311.4 2315.0 2315.2 2315.8 2328.7 2388.9 2389.9 2391.9 2392.0 8973.5 9082.7 9095.6 9107.1 9109.3 5282.5 5411.5 5415.8 5418.9 5423.2 3345.0 3403.2 3548.7 3552.3 3562.3 1068.5 1079.4 1085.2 1108.8 1135.5 12107.5 12142.4 12148.2 12222.6 12226.7 4551.2 4659.7 4736.8 4787.0 4790.4 10217.8 10251.4 10273.2 10278.0 10280.4 5315.0 5320.1 5324.0 5325.4 5326.0 4834.3 4872.8 4889.7 4897.7 4956.4 3791.9 3822.1 3830.4 3880.3 3919.3 1194.0 1197.6 1199.1 1210.9 1216.7 988.1 995.4 1000.6 1002.6 1014.1 5720.0 5810.5 5921.9 5928.9 6082.3 479.6 487.5 487.8 489.8 492.9 3879.7 4069.1 4075.8 4157.2 4169.1 10960.2 11439.4 11445.8 11471.1 11471.8 11357.1 11363.7 11378.2 11392.0 11437.7 7931.1 7979.2 8055.6 8075.4 8076.0 5610.0 5614.6 5648.2 5661.6 5910.4 10088.0 10369.4 10404.7 10406.9 10562.8 9925.4 10073.9 10884.5 10935.7 10981.6 6665.7 7870.6 8135.7 8203.0 8223.9 8158.0 8251.6 8261.3 8466.4 8494.6 7315.6 7386.7 7513.5 7772.9 7988.6 7406.6 7419.1 7461.5 7701.3 7781.8 7181.7 7212.0 7289.3 7304.2 7310.7 5100.6 5201.9 5252.6 5285.2 5317.2 5318.9 5359.1 5364.8 5475.9 5532.3 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | [#4] prototype.html Cycles:180 Average:4548.09 Median:5105.93 stddev:1333.75 (26.1%) stddev-sans-first:1298.98 22:41:24 INFO - PROCESS | 18097 | Values: 308.7 310.2 315.6 330.7 337.8 5216.2 5243.8 5268.6 5292.5 5319.3 4888.3 4988.9 5117.6 5129.8 5145.5 4106.9 4109.8 4116.7 4125.7 4137.5 4125.2 4126.4 4129.3 4130.9 4140.4 3881.5 3951.7 4085.7 4106.4 4130.8 4023.6 4025.5 4055.2 4066.8 4110.5 3615.2 3626.3 3627.4 3629.0 3635.5 4858.3 5031.9 5095.7 5116.2 5124.0 4648.5 4652.0 4656.3 4656.8 4661.3 5406.3 5428.2 5433.3 5500.8 5523.9 3749.9 3916.4 4002.8 4018.4 4021.5 5592.9 5594.0 5600.5 5606.4 5612.8 4144.3 4144.8 4162.4 4169.0 4175.6 3925.5 4224.6 4226.0 4229.9 4239.5 4630.7 4649.5 4654.6 4671.0 4682.7 5474.3 5476.3 5514.8 5515.4 5520.6 1360.6 1421.6 1433.0 1436.0 1456.3 904.1 940.5 940.8 941.4 950.8 4686.6 4700.3 4713.8 4717.7 4759.6 4683.3 4828.4 4883.2 4947.7 4968.7 4698.8 4711.0 4712.3 4720.5 4729.3 2595.1 2601.4 2622.6 2624.6 2644.4 5167.4 5449.3 5822.7 5888.4 5894.6 5740.8 5754.3 5754.5 5757.8 5769.8 5749.5 5866.7 5890.4 5923.5 5928.7 5531.7 5536.4 5563.8 5565.7 5574.8 5499.8 5507.1 5553.8 5568.7 5581.9 5635.0 5781.5 5822.5 5826.6 5890.8 4041.0 4064.8 4082.7 4088.7 4107.0 5434.3 5528.7 5534.1 5537.7 5684.2 5003.8 5337.8 5339.5 5363.4 5365.5 5334.1 5345.9 5383.6 5425.7 5443.6 5273.4 5336.4 5381.2 5425.6 5439.8 5425.6 5461.4 5477.5 5498.4 5506.3 5308.9 5384.5 5386.5 5396.9 5400.2 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - PROCESS | 18097 | [#5] yui.html Cycles:180 Average:20745.29 Median:4790.01 stddev:29464.20 (615.1%) stddev-sans-first:29508.48 22:41:24 INFO - PROCESS | 18097 | Values: 718.4 776.6 782.7 810.5 827.8 15628.6 16038.8 16264.0 16282.0 16579.4 15225.6 15291.8 15314.6 15344.7 15355.6 666.3 800.9 808.7 825.9 831.9 818.6 829.0 831.1 832.3 832.8 886.7 886.9 887.7 888.5 889.0 700.5 720.9 882.8 884.8 885.3 529.5 627.2 641.3 643.2 648.2 14609.7 14643.4 14668.5 14698.9 14701.9 14231.8 14512.1 14746.6 14772.4 15152.1 13350.0 13518.3 13586.9 13629.3 13803.8 4464.4 4492.8 4516.9 4637.8 4708.3 81942.0 81943.6 81975.6 82137.0 82652.0 11553.1 11659.8 12004.4 12058.1 12061.3 22499.2 22727.2 22731.0 22766.4 22767.5 7943.2 8760.3 8825.7 8900.5 9037.9 4598.4 4820.4 4861.5 4915.9 4952.3 3417.6 3533.7 3538.9 3540.0 3542.1 488.6 604.0 606.8 607.6 610.0 633.6 651.2 666.2 673.2 674.4 8986.4 9349.5 9838.1 9966.9 9999.8 570.9 584.0 607.2 652.5 652.9 4700.2 4730.3 4738.5 4759.6 4944.2 73421.6 89448.6 125235.0 133680.3 133973.0 60735.0 62396.0 64882.4 78372.6 93614.0 94180.0 97492.5 100523.0 101325.0 104085.0 8938.5 9010.7 9016.5 9046.5 9073.5 54143.5 55686.4 55706.7 56303.4 57290.1 69167.7 70112.6 70602.3 72973.3 73802.8 12228.9 12726.6 13132.4 13672.3 13749.2 14441.1 14731.6 14858.7 15051.2 15937.6 13222.1 13750.7 14460.7 14767.2 14888.9 13692.8 13791.9 14264.9 15224.6 15618.0 13340.3 13908.9 14007.7 14039.2 14302.2 10878.7 11553.7 11814.0 12029.2 12287.2 11365.6 11668.8 11694.1 12120.8 12361.0 22:41:24 INFO - PROCESS | 18097 | -------- Summary: end -------- 22:41:24 INFO - PROCESS | 18097 | 22:41:24 INFO - TEST-INFO | 18097: exit 0 22:41:24 INFO - TEST-OK | dromaeo_css | took 1115199ms 22:41:24 INFO - TEST-START | kraken 22:41:24 INFO - operating with platform_type : linux_ 22:41:24 INFO - Initialising browser for kraken test... 22:41:24 INFO - TEST-INFO | started process 18289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMO26dr/profile http://localhost:51868/getInfo.html) 22:41:28 INFO - PROCESS | 18289 | __metrics Screen width/height:1600/1200 22:41:28 INFO - PROCESS | 18289 | colorDepth:24 22:41:28 INFO - PROCESS | 18289 | Browser inner width/height: 1024/697 22:41:28 INFO - PROCESS | 18289 | __metrics 22:41:29 INFO - PROCESS | 18289 | 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:41:29 INFO - PROCESS | 18289 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:41:30 INFO - PROCESS | 18289 | console.error: PushService: 22:41:30 INFO - PROCESS | 18289 | stateChangeProcessEnqueue: Error transitioning state 22:41:30 INFO - PROCESS | 18289 | UnknownError 22:41:30 INFO - TEST-INFO | 18289: exit 0 22:41:30 INFO - Browser initialized. 22:41:30 INFO - Running cycle 1/1 for kraken test... 22:41:30 INFO - TEST-INFO | started process 18372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMO26dr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:41:42 INFO - PROCESS | 18372 | RSS: Main: 157184000 22:41:42 INFO - PROCESS | 18372 | 22:41:44 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51868/tests/kraken/driver.html?testName=audio-beat-detection) 22:41:45 INFO - PROCESS | 18372 | RSS: Main: 213397504 22:41:45 INFO - PROCESS | 18372 | 22:41:48 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51868/tests/kraken/driver.html?testName=audio-dft) 22:41:48 INFO - PROCESS | 18372 | RSS: Main: 191770624 22:41:48 INFO - PROCESS | 18372 | 22:41:52 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51868/tests/kraken/driver.html?testName=audio-fft) 22:41:52 INFO - PROCESS | 18372 | RSS: Main: 269230080 22:41:52 INFO - PROCESS | 18372 | 22:41:54 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51868/tests/kraken/driver.html?testName=audio-oscillator) 22:41:55 INFO - PROCESS | 18372 | RSS: Main: 180035584 22:41:55 INFO - PROCESS | 18372 | 22:41:57 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51868/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:58 INFO - PROCESS | 18372 | RSS: Main: 184811520 22:41:58 INFO - PROCESS | 18372 | 22:42:01 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51868/tests/kraken/driver.html?testName=imaging-darkroom) 22:42:01 INFO - PROCESS | 18372 | RSS: Main: 219504640 22:42:01 INFO - PROCESS | 18372 | 22:42:05 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51868/tests/kraken/driver.html?testName=imaging-desaturate) 22:42:05 INFO - PROCESS | 18372 | RSS: Main: 219971584 22:42:05 INFO - PROCESS | 18372 | 22:42:08 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51868/tests/kraken/driver.html?testName=json-parse-financial) 22:42:09 INFO - PROCESS | 18372 | RSS: Main: 219623424 22:42:09 INFO - PROCESS | 18372 | 22:42:10 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51868/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:42:11 INFO - PROCESS | 18372 | RSS: Main: 184164352 22:42:11 INFO - PROCESS | 18372 | 22:42:12 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:42:13 INFO - PROCESS | 18372 | RSS: Main: 189173760 22:42:13 INFO - PROCESS | 18372 | 22:42:15 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:42:16 INFO - PROCESS | 18372 | RSS: Main: 194592768 22:42:16 INFO - PROCESS | 18372 | 22:42:18 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:42:19 INFO - PROCESS | 18372 | RSS: Main: 188149760 22:42:19 INFO - PROCESS | 18372 | 22:42:22 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:42:22 INFO - PROCESS | 18372 | RSS: Main: 192851968 22:42:22 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | Cycle 1(1): loaded http://localhost:51868/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51868/tests/kraken/driver.html?testName=ai-astar) 22:42:24 INFO - PROCESS | 18372 | RSS: Main: 243339264 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | __start_tp_report 22:42:24 INFO - PROCESS | 18372 | _x_x_mozilla_page_load 22:42:24 INFO - PROCESS | 18372 | _x_x_mozilla_page_load_details 22:42:24 INFO - PROCESS | 18372 | |i|pagename|runs| 22:42:24 INFO - PROCESS | 18372 | |0;ai-astar;101;113;110;111;113;111;120;110;109;111 22:42:24 INFO - PROCESS | 18372 | |1;audio-beat-detection;140;115;120;187;118;119;145;117;150;119 22:42:24 INFO - PROCESS | 18372 | |2;audio-dft;160;160;160;162;160;162;161;161;162;160 22:42:24 INFO - PROCESS | 18372 | |3;audio-fft;74;75;75;108;76;76;74;74;102;75 22:42:24 INFO - PROCESS | 18372 | |4;audio-oscillator;84;85;117;84;84;84;118;83;83;137 22:42:24 INFO - PROCESS | 18372 | |5;imaging-gaussian-blur;116;116;116;117;117;116;116;116;117;117 22:42:24 INFO - PROCESS | 18372 | |6;imaging-darkroom;146;146;147;145;146;146;148;172;146;146 22:42:24 INFO - PROCESS | 18372 | |7;imaging-desaturate;95;95;94;95;96;95;95;95;95;95 22:42:24 INFO - PROCESS | 18372 | |8;json-parse-financial;51;49;49;50;49;50;49;50;49;49 22:42:24 INFO - PROCESS | 18372 | |9;json-stringify-tinderbox;61;60;60;61;61;60;61;60;61;64 22:42:24 INFO - PROCESS | 18372 | |10;stanford-crypto-aes;78;76;77;77;77;85;76;77;77;76 22:42:24 INFO - PROCESS | 18372 | |11;stanford-crypto-ccm;122;122;121;122;130;123;122;122;142;125 22:42:24 INFO - PROCESS | 18372 | |12;stanford-crypto-pbkdf2;161;157;156;158;157;158;162;158;160;158 22:42:24 INFO - PROCESS | 18372 | |13;stanford-crypto-sha256-iterative;64;63;66;63;63;70;63;63;62;63 22:42:24 INFO - PROCESS | 18372 | __end_tp_report 22:42:24 INFO - PROCESS | 18372 | __start_cc_report 22:42:24 INFO - PROCESS | 18372 | _x_x_mozilla_cycle_collect,1692 22:42:24 INFO - PROCESS | 18372 | __end_cc_report 22:42:24 INFO - PROCESS | 18372 | __startTimestamp1470462144596__endTimestamp 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | ------- Summary: start ------- 22:42:24 INFO - PROCESS | 18372 | Number of tests: 14 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:4.65 (4.2%) stddev-sans-first:3.28 22:42:24 INFO - PROCESS | 18372 | Values: 101.0 113.0 110.0 111.0 113.0 111.0 120.0 110.0 109.0 111.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:130.00 stddev:23.01 (17.7%) stddev-sans-first:24.26 22:42:24 INFO - PROCESS | 18372 | Values: 140.0 115.0 120.0 187.0 118.0 119.0 145.0 117.0 150.0 119.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 22:42:24 INFO - PROCESS | 18372 | Values: 160.0 160.0 160.0 162.0 160.0 162.0 161.0 161.0 162.0 160.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:12.80 (17.1%) stddev-sans-first:13.33 22:42:24 INFO - PROCESS | 18372 | Values: 74.0 75.0 75.0 108.0 76.0 76.0 74.0 74.0 102.0 75.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:84.00 stddev:20.11 (23.9%) stddev-sans-first:20.87 22:42:24 INFO - PROCESS | 18372 | Values: 84.0 85.0 117.0 84.0 84.0 84.0 118.0 83.0 83.0 137.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:42:24 INFO - PROCESS | 18372 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:146.00 stddev:8.19 (5.6%) stddev-sans-first:8.62 22:42:24 INFO - PROCESS | 18372 | Values: 146.0 146.0 147.0 145.0 146.0 146.0 148.0 172.0 146.0 146.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 22:42:24 INFO - PROCESS | 18372 | Values: 95.0 95.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:49.50 stddev:0.71 (1.4%) stddev-sans-first:0.50 22:42:24 INFO - PROCESS | 18372 | Values: 51.0 49.0 49.0 50.0 49.0 50.0 49.0 50.0 49.0 49.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:1.20 (2.0%) stddev-sans-first:1.27 22:42:24 INFO - PROCESS | 18372 | Values: 61.0 60.0 60.0 61.0 61.0 60.0 61.0 60.0 61.0 64.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:77.00 stddev:2.67 (3.5%) stddev-sans-first:2.83 22:42:24 INFO - PROCESS | 18372 | Values: 78.0 76.0 77.0 77.0 77.0 85.0 76.0 77.0 77.0 76.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:122.50 stddev:6.49 (5.3%) stddev-sans-first:6.78 22:42:24 INFO - PROCESS | 18372 | Values: 122.0 122.0 121.0 122.0 130.0 123.0 122.0 122.0 142.0 125.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.50 Median:158.00 stddev:1.90 (1.2%) stddev-sans-first:1.79 22:42:24 INFO - PROCESS | 18372 | Values: 161.0 157.0 156.0 158.0 157.0 158.0 162.0 158.0 160.0 158.0 22:42:24 INFO - PROCESS | 18372 | 22:42:24 INFO - PROCESS | 18372 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:63.00 stddev:2.36 (3.7%) stddev-sans-first:2.50 22:42:24 INFO - PROCESS | 18372 | Values: 64.0 63.0 66.0 63.0 63.0 70.0 63.0 63.0 62.0 63.0 22:42:24 INFO - PROCESS | 18372 | -------- Summary: end -------- 22:42:24 INFO - PROCESS | 18372 | 22:42:25 INFO - TEST-INFO | 18372: exit 0 22:42:25 INFO - TEST-OK | kraken | took 60920ms 22:42:25 INFO - SUITE-END | took 1176s 22:42:25 INFO - Completed test suite (00:19:36) 22:42:25 INFO - javascript benchmark 22:42:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3007.3684526249485, 3036.6507851597066, 3044.406340763551, 3044.558539011845, 3085.4908940024898, 5264.736763161845, 5293.735313234337, 5310.946890531093, 5369.328833895763, 5411.945880541199, 5354.089804733195, 5357.973210133944, 5363.6245462817615, 5436.401995780461, 5438.7280635968245, 4862.951370486294, 4937.061958227943, 4945.23348880924, 4952.702837829724, 5058.721770302632, 4991.101601711691, 5028.49715028496, 5036.294918711365, 5054.9494505054845, 5057.898842023157, 4833.830815921444, 4838.104950584153, 4868.707877527359, 4882.998985208059, 5004.774785134678, 4878.438979517366, 4890.168671325883, 4899.706017638953, 4960.355153830015, 4971.850844474636, 4893.75531223441, 4931.422099596346, 4933.45731969483, 4974.950250497485, 4996.9500304996845, 5291.947080529222, 5347.946520534783, 5412.269343638637, 5413.621046526745, 5469.371022332443, 5733.3406658234, 5778.219940308047, 5827.125931110325, 5833.300003999505, 5850.531957443394, 5878.088896221093, 5883.735231914574, 5885.117232415129, 5893.499052580536, 5909.852253693649, 5277.736113194325, 5342.866428339284, 5382.381026181961, 5386.326709161355, 5486.259354987065, 5917.171595976567, 5924.0, 5968.85077873048, 5969.253843269591, 5972.462478376967, 5691.231683722727, 5697.601167918288, 5738.741756620962, 5747.3677895431465, 5749.1663708762835, 5604.299462567179, 5700.914486282709, 5749.9712501437225, 5767.596268261182, 5822.912656310159, 5770.6249093808765, 5773.162891380726, 5774.942250577525, 5793.507551858055, 5818.8254352369495, 5746.741396637161, 5786.305643322744, 5794.0, 5799.4780469757925, 5801.622894511843, 4346.630536404377, 4351.325544540601, 4355.847545335898, 4414.889627759332, 4440.733555986587, 3919.2161567686576, 3968.0873399117786, 3980.5024371953505, 3981.581933896899, 4071.877843664695, 5561.610687251934, 5567.24842146305, 5570.08093664549, 5570.805021824298, 5617.691026993554, 5849.824505264849, 5850.385709500526, 5855.121731740273, 5859.44335288147, 5862.765489380377, 5295.364556253275, 5437.401885792559, 5488.094464413407, 5493.587980901369, 5580.91628625571, 4971.204607262821, 5004.624653151028, 5013.3983921928875, 5015.0, 5042.067217564762, 5999.820005399845, 6048.667323297261, 6059.36376680451, 6067.423594758579, 6068.575199735976, 5655.943440565542, 5771.220885180447, 5814.04068328738, 5819.388964158702, 5901.380355062656, 6042.395760423922, 6044.576879618385, 6046.213992181164, 6065.545084118797, 6096.116063170904, 5715.257016587983, 5743.885122297447, 5748.597598168255, 5749.195112684311, 5750.971245143747, 5686.800961966312, 5777.480026797609, 5797.91303130445, 5826.475617194473, 5829.6793676346515, 5668.3198016236665, 5726.255586773859, 5726.770929162787, 5729.427057294237, 5764.913526297193, 5655.1517272409465, 5697.287839020122, 5716.971415142898, 5718.142278658234, 5719.342275638355, 5447.836564903059, 5538.280023596906, 5571.526420254324, 5594.300712410949, 5654.095344744821, 5700.942990570041, 5717.8570535736935, 5740.913886291625, 5744.454276843693, 5746.109353050284, 5576.0, 5582.944170558242, 5589.301337332833, 5591.04952158126, 5591.524720398812], "value": 5347.667432770037, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.8380437281934, 600.7626987339999, 601.8465291350708, 621.9892674404093, 633.5596760251625, 32700.83649581752, 42865.571344286545, 43706.0, 43765.56234437662, 43774.12451750967, 45146.774266128625, 45546.316805247945, 45564.0, 45568.3164752529, 45631.77184114083, 8998.820023599525, 9103.590338434753, 9113.772155696128, 9133.223675987549, 9161.404508706946, 8953.23897468716, 9023.278137748997, 9035.051319611444, 9041.231495322905, 9047.547622618875, 9025.097490250955, 9046.728598142034, 9068.274538036942, 9068.591913363915, 9072.36493445459, 8982.730518084436, 9025.142611451902, 9047.909520904805, 9052.77368065797, 9092.272618190595, 8983.281337492985, 9059.50172740499, 9060.275177985812, 9060.54697265134, 9064.138906803842, 42788.35817462741, 43051.0, 43178.35232471515, 43227.0, 43243.783781081205, 3657.9634203658156, 4130.91738165238, 4131.669466442708, 4182.121754431573, 4300.462442194726, 32815.0, 32918.6708132918, 33285.334293314, 33493.49759753606, 34463.6553634463, 4023.517177938666, 4209.97895010526, 4218.472690913636, 4232.132412855357, 4250.3199488081755, 375584.0, 375724.0, 376085.0, 376135.0, 377304.1134794336, 45508.0, 45944.54055459469, 46095.539044609795, 46096.539034609894, 46107.76946115248, 61043.389566104655, 61089.0, 61162.0, 61165.694171528856, 61213.38786612166, 12892.48430062787, 13568.864311356958, 13808.930955345159, 13896.444142234197, 14257.714845703027, 10388.220883433773, 10391.116755075904, 10396.428196449267, 10417.531211095518, 10450.581976720847, 16304.51086467408, 16396.0, 16470.917645411697, 16518.42185523525, 16533.35466325382, 5559.8888022239325, 6563.158788678081, 6722.327767223336, 6931.09895713554, 6931.896021559681, 993.3791380387258, 1047.3297089862494, 1070.1920050361928, 1073.2272763610188, 1183.1126655008743, 32984.67015329816, 33005.08116372124, 33154.668453315644, 33155.66844331574, 33449.83275083609, 1113.3820729495208, 1121.3047910295506, 1127.22784892349, 1147.5868687272575, 1208.9637310880687, 17151.0, 17197.7420338695, 17294.567635808955, 17315.653686926442, 17359.0, 519567.0, 525955.0, 529108.0, 531050.0, 533386.0, 57759.42240577625, 57965.710171448874, 58069.70965145147, 58072.419275807544, 58097.4190258092, 1205.813098969661, 1226.9509219631116, 1352.4725357110735, 1380.3581334679623, 1383.508854356709, 32150.35699286048, 33443.498347525296, 33790.831045845596, 33945.66054339425, 34550.0, 50839.2374114396, 54091.72954135361, 56686.433135669766, 56747.0, 61931.0, 354302.0, 354827.0, 357098.0, 357505.0, 357773.0, 25745.613815793153, 25834.35414114587, 26023.0, 28485.287867803472, 29019.85490072536, 48511.51488485211, 48537.757311213216, 48778.512214878814, 48915.96097657632, 49923.750381249316, 34766.47850282197, 35394.46908296429, 35394.646053540164, 35818.82090589635, 35911.10222244465, 40411.39382909318, 40486.0, 40911.1817763649, 41042.38436423516, 42567.0, 34813.12967175841, 35186.64813351936, 35440.46839297361, 35531.28937421289, 35781.64218357887, 34593.30813383769, 36084.458733118496, 37379.81310093432, 37421.0, 37740.43389349107, 28730.0, 28879.120879120877, 29035.7096429033, 29226.853865731387, 29239.7076029237], "value": 19677.51585586663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.1708723720577, 377.8469719763495, 388.4134956216114, 388.5570449687356, 421.77224298878605, 16608.0, 19037.714434283498, 19170.520736981583, 19939.700904486443, 20073.899630501826, 19539.609207815836, 19747.0, 20029.799702002976, 20058.297959571417, 20179.394618161477, 5313.814016509423, 5598.748056337475, 5674.845872325809, 5790.942090579104, 5803.0, 5731.312242530902, 5807.245058142456, 5813.186153938452, 5813.73838177281, 5816.621919575236, 5604.41153678864, 5747.166660834177, 5752.769889204447, 5768.653880767147, 5780.595358324919, 5716.428357164292, 5739.081746920473, 5745.195672605839, 5749.568782341299, 5757.539396848242, 4905.2151655734915, 5044.445111037783, 5075.467075957008, 5080.669756465818, 5082.034413461431, 23871.761282387302, 23997.520049599087, 24133.517329653485, 24289.0, 24311.14910978125, 2343.2267351773876, 2390.497995420964, 2590.2876708905014, 2621.3839747659285, 2912.723291287324, 14120.293985300694, 14683.339249733788, 15090.094594324266, 15522.0, 15564.766528502081, 1881.5860510687626, 1884.274554296592, 1915.817997290255, 1933.5262860599244, 1944.562473443464, 78087.21912780857, 80804.0, 81056.59471702663, 82276.0, 86227.0, 11141.498632561554, 11152.832707509393, 11286.492107855167, 11462.197646164856, 11557.30656160633, 15622.0, 15622.140782257085, 15630.45293414725, 15637.60905977337, 15660.0, 7895.565743884026, 7942.3646108311195, 8010.79973000668, 8233.34133269337, 8254.711085112112, 7758.573278469738, 7770.883436748453, 8020.919790802017, 8055.677772889018, 8057.879131813028, 4765.332853400527, 4831.8308859190465, 4839.854804355875, 4903.190973489405, 4948.950510494921, 1955.1983686688388, 2001.4996250937265, 2014.6776515757408, 2020.2626041494748, 2033.7152798608206, 916.3677062826629, 934.2946075712794, 944.0087907696891, 1037.6264544763878, 1041.510490069666, 19521.90239048824, 19873.701894471593, 20011.799882001284, 20081.698774518394, 20248.898755506423, 977.2084611464736, 982.9557669904872, 987.437160818341, 999.2905037423511, 1012.0486742462062, 10215.284930054973, 10292.433916134685, 10602.469876506144, 10670.946645266724, 10732.624358147428, 467758.0, 468496.0, 470559.0, 471399.0, 474374.0, 74993.0, 75619.62190189013, 85925.57037214773, 88707.55646221856, 90461.0, 908.6955869783696, 950.5627411390699, 969.1568335548118, 983.1741337276721, 994.3288280410839, 17254.482365529053, 18116.72824907654, 18342.72485912739, 18345.357912473, 18416.35542755997, 43978.56021439745, 44787.32819007652, 45203.77398112988, 45233.77383113195, 47176.528234718586, 58313.0, 59201.40798591959, 60133.39866601453, 60608.696956514934, 61493.38506614877, 7875.991622136318, 7944.165862584577, 7993.92006079955, 8033.0, 8522.36082293818, 12179.939100304797, 12488.0, 12530.937345313216, 12617.558385456832, 12719.87280127187, 10915.454227288763, 11158.0, 11240.0, 11619.941900290445, 11875.881241187477, 10610.8408373876, 11030.393328367018, 11080.889191107986, 11389.601363952223, 11476.827847582459, 11240.662780116609, 11273.098152147972, 11358.148138889452, 11358.488868001125, 11565.595204167814, 7935.801604959691, 8089.393295502979, 8284.668613255402, 8371.0, 8584.78538036529, 8665.090165532529, 10513.632022879163, 10852.473872106752, 11029.338239705643, 11102.444877756241], "value": 9248.482681973657, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.073233130796, 1500.9324580393882, 1643.9260233289501, 1785.187739578492, 1790.5255107396551, 8466.872996905044, 9167.312451566126, 9168.770780730485, 9173.449593024423, 9174.0, 7866.724664636738, 8134.227248411406, 8143.918560814405, 8149.225823546768, 8954.328425368107, 2345.5308938212343, 2420.225527831095, 2420.479596886669, 2446.2661201639526, 2452.0682140786475, 2333.3816538617193, 2376.9049238030543, 2379.6192609182535, 2396.6884305040408, 2399.1003373734848, 2351.1183306260154, 2379.4170428245106, 2403.7476065013097, 2405.0860672944254, 2406.3743426709093, 2308.7691230876944, 2311.399036250587, 2314.9768502315014, 2315.1896836107394, 2315.81473482121, 2328.6739856420113, 2388.9163879264142, 2389.904403823845, 2391.923634364543, 2391.983407051996, 8973.506457144855, 9082.727518174466, 9095.636174553076, 9107.134822191882, 9109.316801239867, 5282.498162674539, 5411.512963833235, 5415.810446634388, 5418.91871621922, 5423.240746295522, 3345.022663490317, 3403.1832360233366, 3548.733844961638, 3552.342816578941, 3562.2697347043795, 1068.4550879051662, 1079.4440862955505, 1085.2403317677656, 1108.7837871615038, 1135.5174051028362, 12107.515699372014, 12142.392880356036, 12148.21036632616, 12222.633321000385, 12226.694332641755, 4551.226291530448, 4659.697119687277, 4736.7631618418945, 4787.018661174451, 4790.4251489821445, 10217.846732299022, 10251.38491690501, 10273.229507786946, 10277.972202779813, 10280.434576098387, 5314.97342513285, 5320.095583750772, 5324.041672498987, 5325.360956685223, 5326.041312563776, 4834.274858771142, 4872.829450969199, 4889.731064791399, 4897.657163998486, 4956.405231372259, 3791.8862434127022, 3822.120912190212, 3830.367989281793, 3880.262750077476, 3919.2553414851086, 1193.9761204775855, 1197.592818441734, 1199.0827017331749, 1210.9212901161397, 1216.7262365967763, 988.1057642833248, 995.4027583450016, 1000.6197644895038, 1002.6190047781796, 1014.0721240065405, 5719.971400142973, 5810.5061069809535, 5921.851953701106, 5928.881422371528, 6082.2701275847185, 479.6210993315312, 487.51735781576696, 487.81950678248563, 489.8456986049454, 492.91127597032164, 3879.7090218233743, 4069.064115253449, 4075.816588253536, 4157.233794862916, 4169.145325208325, 10960.177986651033, 11439.37083460401, 11445.771084578262, 11471.139664525193, 11471.827922581168, 11357.091432685365, 11363.715907102389, 11378.203525753283, 11391.97472227487, 11437.656870293904, 7931.127575966639, 7979.162187970295, 8055.597220139087, 8075.3539716822515, 8075.959620201861, 5609.990201763721, 5614.6069775116985, 5648.2092507049865, 5661.603687741983, 5910.379410161871, 10088.0, 10369.377837329785, 10404.68785936423, 10406.895931040593, 10562.8415573768, 9925.3548519346, 10073.899261007296, 10884.51019704131, 10935.671929842118, 10981.560737570408, 6665.733370665119, 7870.645820937957, 8135.7152499662225, 8203.0, 8223.876641850258, 8157.959210203911, 8251.587420628824, 8261.297789687944, 8466.407351485337, 8494.57527123629, 7315.597642129734, 7386.741464048732, 7513.549187048795, 7772.92227077722, 7988.6405111768945, 7406.592637404994, 7419.109706835215, 7461.477696561188, 7701.306882380389, 7781.805454863674, 7181.676824542804, 7212.0, 7289.307515785992, 7304.233055529093, 7310.6710198040155, 5100.591952643854, 5201.869953251047, 5252.579793616425, 5285.233641122093, 5317.175837745156, 5318.893622127458, 5359.115745902039, 5364.7585858635675, 5475.890482190413, 5532.28080349552], "value": 4597.130577226809, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.68822489285816, 310.1811218383468, 315.61967828766336, 330.7106282003247, 337.834461114054, 5216.191490318997, 5243.816466423676, 5268.631195816294, 5292.497212764782, 5319.281896943911, 4888.291197776321, 4988.900221995558, 5117.59059275258, 5129.769160387782, 5145.536901678848, 4106.897327566812, 4109.835606575748, 4116.650084742785, 4125.690573207006, 4137.524184718751, 4125.195586860564, 4126.401671757593, 4129.318662420697, 4130.8554200602985, 4140.37894315852, 3881.514810648669, 3951.6641085507767, 4085.7140000199997, 4106.384042393635, 4130.752154870717, 4023.637872591481, 4025.4766880305374, 4055.188962207541, 4066.7763273019823, 4110.527289361732, 3615.204654975901, 3626.274745050974, 3627.4196128619296, 3628.981855090734, 3635.509206257148, 4858.275312264166, 5031.924521132186, 5095.668781529189, 5116.181410974264, 5123.974380128113, 4648.48866624671, 4652.0, 4656.253904785705, 4656.790444429975, 4661.254199328125, 5406.297181366436, 5428.212909128194, 5433.320834895638, 5500.779968801243, 5523.944760552383, 3749.9187950687356, 3916.3733802591732, 4002.779847108408, 4018.417329487208, 4021.4571032910476, 5592.916106258409, 5594.0, 5600.467955544217, 5606.439356064402, 5612.831615051555, 4144.295469770146, 4144.8134833932545, 4162.41726158338, 4169.0, 4175.582441755861, 3925.509311336083, 4224.556421575752, 4225.9577404226175, 4229.851955181553, 4239.491261048633, 4630.745309008037, 4649.511801260886, 4654.581087702123, 4670.95329046712, 4682.675300536589, 5474.343078830566, 5476.288082549243, 5514.751836167382, 5515.365732940683, 5520.558355331564, 1360.6122255157234, 1421.644588852787, 1433.0, 1435.978460323096, 1456.2936975566843, 904.0507467159456, 940.4921342474987, 940.8400571902793, 941.3975055964188, 950.814591154732, 4686.62506999446, 4700.294955756595, 4713.750856023156, 4717.693349932243, 4759.619230461555, 4683.314006957493, 4828.392667959262, 4883.243097319921, 4947.727874966911, 4968.677035992721, 4698.8120475181295, 4710.976445117821, 4712.340272361804, 4720.457147428021, 4729.290606409066, 2595.1046888823325, 2601.414681696603, 2622.645942797698, 2624.580067189279, 2644.405008873027, 5167.431582525919, 5449.3460784706085, 5822.679752613647, 5888.411158884077, 5894.616849904828, 5740.827775166752, 5754.324239228778, 5754.510866576305, 5757.827265182052, 5769.836311383231, 5749.453801888814, 5866.706664666664, 5890.410958904075, 5923.526117910556, 5928.703564821828, 5531.695756733257, 5536.390996990408, 5563.833085007456, 5565.688415392835, 5574.804881829116, 5499.780008799603, 5507.118860982224, 5553.777848885999, 5568.693721845338, 5581.860453488695, 5634.971825140848, 5781.479666830005, 5822.475977162076, 5826.621269617462, 5890.793822216202, 4040.9797951010055, 4064.7764372959773, 4082.7142100053898, 4088.732492927202, 4106.958930410658, 5434.2935418395355, 5528.668279903219, 5534.142207957773, 5537.695426751407, 5684.232628595087, 5003.814789492928, 5337.786488540415, 5339.529305951008, 5363.383210930793, 5365.463453654604, 5334.146536554133, 5345.919811202757, 5383.623146379872, 5425.728713564227, 5443.646162999393, 5273.393559740525, 5336.412994570516, 5381.219723140044, 5425.565954723534, 5439.755211015593, 5425.593080518898, 5461.371942226695, 5477.507024367666, 5498.367687715964, 5506.2557460926955, 5308.946910530845, 5384.461553844584, 5386.48828361305, 5396.92375346049, 5400.1899715043055], "value": 4123.634429231859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.421670555203, 776.5806464509162, 782.667366369293, 810.5177419435441, 827.7889138269741, 15628.609284767886, 16038.75941860873, 16264.024158550495, 16282.0, 16579.41972030979, 15225.619359516018, 15291.770623440629, 15314.617134571641, 15344.693106137898, 15355.616109597266, 666.3037126203105, 800.8758642410436, 808.7492877208084, 825.8637324841394, 831.9376046796484, 818.5866137600503, 829.0300348592161, 831.1065604475183, 832.342449464922, 832.8292699996487, 886.6985225023491, 886.8891388576428, 887.6848718704864, 888.537960260664, 888.9599968001455, 700.5376451541957, 720.9134903811524, 882.7793051737066, 884.7788052986785, 885.313881741649, 529.4916879795401, 627.2253766598247, 641.2753588445074, 643.1799455693981, 648.1962366665348, 14609.70780584393, 14643.41426342945, 14668.486602968846, 14698.853011469855, 14701.926490367587, 14231.78652320216, 14512.129272243594, 14746.55760327181, 14772.409103635842, 15152.090874547452, 13350.0, 13518.324083795771, 13586.86413135866, 13629.318534073256, 13803.792943105862, 4464.352668863029, 4492.84275050375, 4516.864494065183, 4637.814487420499, 4708.270218116198, 81942.0, 81943.5902820488, 81975.5901220496, 82137.0, 82652.0, 11553.13351498624, 11659.766804664028, 12004.399780010965, 12058.095642826647, 12061.336626485452, 22499.212527561456, 22727.204547841076, 22731.0, 22766.430839228822, 22767.544649106923, 7943.165967573437, 8760.255378292917, 8825.735227943173, 8900.510471924106, 9037.864432033524, 4598.425196850394, 4820.4456487503685, 4861.489543597942, 4915.90168196634, 4952.281919121707, 3417.5557177567252, 3533.6643018913164, 3538.858445662196, 3539.964600354015, 3542.079059444563, 488.63840757839654, 604.0425924908981, 606.8179546136141, 607.5868409481595, 610.0209164291338, 633.5596760251581, 651.221789960992, 666.1772710702279, 673.1988933169513, 674.3559900295206, 8986.415882967585, 9349.53252337394, 9838.114569688762, 9966.856886540847, 9999.750006249757, 570.8829689913641, 583.9591228614041, 607.1560530862105, 652.4649787174566, 652.9412352888263, 4700.2009658358165, 4730.337752714623, 4738.549837765407, 4759.571638552547, 4944.159492886218, 73421.6328918352, 89448.55275723709, 125235.0, 133680.33159834333, 133973.0, 60735.0, 62396.0, 64882.35117648858, 78372.60813695895, 93614.0, 94180.0, 97492.51253743687, 100522.99477005283, 101325.0, 104085.0, 8938.46369217849, 9010.684626038057, 9016.504092274725, 9046.50244236547, 9073.546322683971, 54143.45856541384, 55686.44313556812, 55706.72146639241, 56303.43696562982, 57290.14064788948, 69167.65416173088, 70112.64943675249, 70602.29397705957, 72973.27026729664, 73802.7859164226, 12228.938855305667, 12726.618201453972, 13132.40904159296, 13672.316384180553, 13749.175049497062, 14441.133531988115, 14731.631709207355, 14858.702825943637, 15051.247437627855, 15937.60155996063, 13222.140560863512, 13750.65623359424, 14460.710785784015, 14767.187804670846, 14888.925555372154, 13692.782196183689, 13791.862081379059, 14264.928675356558, 15224.619384515032, 15618.0, 13340.26628535401, 13908.860911390757, 14007.719845603235, 14039.157650540592, 14302.213378264296, 10878.681853147658, 11553.653390398302, 11814.0, 12029.15795894279, 12287.201331913397, 11365.615229350615, 11668.766624667629, 11694.064474842162, 12120.818187727367, 12361.0], "value": 7635.01558563912, "unit": "score"}], "name": "dromaeo_css", "value": 7213.365782956573, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 110.0, 111.0, 113.0, 111.0, 120.0, 110.0, 109.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 115.0, 120.0, 187.0, 118.0, 119.0, 145.0, 117.0, 150.0, 119.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 162.0, 160.0, 162.0, 161.0, 161.0, 162.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 108.0, 76.0, 76.0, 74.0, 74.0, 102.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 117.0, 84.0, 84.0, 84.0, 118.0, 83.0, 83.0, 137.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 148.0, 172.0, 146.0, 146.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 50.0, 49.0, 50.0, 49.0, 50.0, 49.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 61.0, 61.0, 60.0, 61.0, 60.0, 61.0, 64.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 77.0, 77.0, 85.0, 76.0, 77.0, 77.0, 76.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 121.0, 122.0, 130.0, 123.0, 122.0, 122.0, 142.0, 125.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 157.0, 156.0, 158.0, 157.0, 158.0, 162.0, 158.0, 160.0, 158.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 63.0, 63.0, 70.0, 63.0, 63.0, 62.0, 63.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1477.3, "alertThreshold": 2.0}]} 22:42:25 INFO - Return code: 0 22:42:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:42:25 INFO - # TBPL SUCCESS # 22:42:25 INFO - Running post-action listener: _resource_record_post_action 22:42:25 INFO - [mozharness: 2016-08-06 05:42:25.920811Z] Finished run-tests step (success) 22:42:25 INFO - Running post-run listener: _resource_record_post_run 22:42:25 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 554557440; Read time: 228; Write time: 506384 22:42:25 INFO - TinderboxPrint: CPU usage
13.0% 22:42:25 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 228 22:42:25 INFO - TinderboxPrint: I/O write bytes / time
554,557,440 / 506,384 22:42:25 INFO - TinderboxPrint: CPU idle
8,285.4 (87.0%) 22:42:25 INFO - TinderboxPrint: CPU user
1,189.2 (12.5%) 22:42:25 INFO - TinderboxPrint: Swap in / out
0 / 0 22:42:25 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 260640768; Read time: 216; Write time: 299032 22:42:26 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 293916672; Read time: 0; Write time: 207352 22:42:26 INFO - Running post-run listener: _upload_blobber_files 22:42:26 INFO - Blob upload gear active. 22:42:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:42: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:42: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:42: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:42:26 INFO - (blobuploader) - INFO - Open directory for files ... 22:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:27 INFO - (blobuploader) - INFO - Done attempting. 22:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:32 INFO - (blobuploader) - INFO - Done attempting. 22:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:33 INFO - (blobuploader) - INFO - Done attempting. 22:42:33 INFO - (blobuploader) - INFO - Iteration through files over. 22:42:33 INFO - Return code: 0 22:42:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:42:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:42:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e036fcdccacc0b593ed583542366a20fea6d4e942e238459c2be201da048a5f23656d697140b3d6fb0d2ee811acbe4cf6624d7a436eeb4f856b3a03082d46b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d14a969847d4dd85fa19a2b955692b0c54ea552a8c5b8030dd58b8fff844198bc163a9403d38871fcc9582311e2709182459a24f3609cff0af2c13697723e4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb910c6239c7ea2681d47be1da0cf87898ed6e5e129c3fbffe99f9752e2e57f6d15b3caf80516f08854313e0af3095b8ea84ab14bc206297ae231445224bbc5a"} 22:42:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:42:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:42:33 INFO - Contents: 22:42:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e036fcdccacc0b593ed583542366a20fea6d4e942e238459c2be201da048a5f23656d697140b3d6fb0d2ee811acbe4cf6624d7a436eeb4f856b3a03082d46b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d14a969847d4dd85fa19a2b955692b0c54ea552a8c5b8030dd58b8fff844198bc163a9403d38871fcc9582311e2709182459a24f3609cff0af2c13697723e4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb910c6239c7ea2681d47be1da0cf87898ed6e5e129c3fbffe99f9752e2e57f6d15b3caf80516f08854313e0af3095b8ea84ab14bc206297ae231445224bbc5a"} 22:42:33 INFO - Running post-run listener: copy_logs_to_upload_dir 22:42:33 INFO - Copying logs to upload dir... 22:42:33 INFO - mkdir: /builds/slave/test/build/upload/logs 22:42:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.559180 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-05 22:42:32.723426) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 22:42:32.726614) ========= 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=a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347 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/e036fcdccacc0b593ed583542366a20fea6d4e942e238459c2be201da048a5f23656d697140b3d6fb0d2ee811acbe4cf6624d7a436eeb4f856b3a03082d46b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d14a969847d4dd85fa19a2b955692b0c54ea552a8c5b8030dd58b8fff844198bc163a9403d38871fcc9582311e2709182459a24f3609cff0af2c13697723e4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb910c6239c7ea2681d47be1da0cf87898ed6e5e129c3fbffe99f9752e2e57f6d15b3caf80516f08854313e0af3095b8ea84ab14bc206297ae231445224bbc5a"} 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.011174 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/e036fcdccacc0b593ed583542366a20fea6d4e942e238459c2be201da048a5f23656d697140b3d6fb0d2ee811acbe4cf6624d7a436eeb4f856b3a03082d46b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d14a969847d4dd85fa19a2b955692b0c54ea552a8c5b8030dd58b8fff844198bc163a9403d38871fcc9582311e2709182459a24f3609cff0af2c13697723e4df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb910c6239c7ea2681d47be1da0cf87898ed6e5e129c3fbffe99f9752e2e57f6d15b3caf80516f08854313e0af3095b8ea84ab14bc206297ae231445224bbc5a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 22:42:32.751752) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:42:32.752062) ========= 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=a0d2d2de893abaa8702a9954000001a7-1470458607.994640-519502347 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:42:32.837602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 22:42:32.837941) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 22:42:32.838295) ========= ========= Total master_lag: 0.17 =========