builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1470507728.52 results: success (0) buildid: 20160806083007 builduid: ce654325fd60496c989bb2f44ace56ba revision: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.524899) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.525783) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.526096) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.568404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.568706) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-06 11:22:09-- 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% 65.7K=0.2s 2016-08-06 11:22:09 (65.7 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.304656 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.890884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.891163) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021776 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.946247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:08.946570) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 --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=78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-06 11:22:09,439 truncating revision to first 12 chars 2016-08-06 11:22:09,439 Setting DEBUG logging. 2016-08-06 11:22:09,439 attempt 1/10 2016-08-06 11:22:09,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fa0934fbb4d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-06 11:22:09,745 unpacking tar archive at: fx-team-fa0934fbb4d5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655804 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:09.616891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:09.617287) ========= script_repo_revision: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:09.617799) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:09.618175) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 11:22:09.632029) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-06 11:22:09.632505) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:22:10 INFO - MultiFileLogger online at 20160806 11:22:10 in /builds/slave/test 11:22:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:22:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:22:10 INFO - {'append_to_log': False, 11:22:10 INFO - 'base_work_dir': '/builds/slave/test', 11:22:10 INFO - 'blob_upload_branch': 'Fx-Team', 11:22:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:22:10 INFO - 'branch': 'Fx-Team', 11:22:10 INFO - 'buildbot_json_path': 'buildprops.json', 11:22:10 INFO - 'config_files': ('talos/linux_config.py',), 11:22:10 INFO - 'default_actions': ('clobber', 11:22:10 INFO - 'read-buildbot-config', 11:22:10 INFO - 'download-and-extract', 11:22:10 INFO - 'populate-webroot', 11:22:10 INFO - 'create-virtualenv', 11:22:10 INFO - 'install', 11:22:10 INFO - 'run-tests'), 11:22:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:22:10 INFO - 'download_minidump_stackwalk': True, 11:22:10 INFO - 'download_symbols': 'ondemand', 11:22:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:22:10 INFO - 'tooltool.py': '/tools/tooltool.py', 11:22:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:22:10 INFO - '/tools/misc-python/virtualenv.py')}, 11:22:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:22:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:22:10 INFO - 'installer_path': 'installer.exe', 11:22:10 INFO - 'log_level': 'info', 11:22:10 INFO - 'log_name': 'talos', 11:22:10 INFO - 'log_to_console': True, 11:22:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:22:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:22:10 INFO - 'opt_config_files': (), 11:22:10 INFO - 'pip_index': False, 11:22:10 INFO - 'sps_profile': False, 11:22:10 INFO - 'sps_profile_interval': 0, 11:22:10 INFO - 'suite': 'dromaeojs', 11:22:10 INFO - 'system_bits': '32', 11:22:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:22:10 INFO - 'title': 'talos-linux64-ix-019', 11:22:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:22:10 INFO - 'use_talos_json': True, 11:22:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:22:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:22:10 INFO - 'work_dir': 'build'} 11:22:10 INFO - [mozharness: 2016-08-06 18:22:10.219946Z] Running clobber step. 11:22:10 INFO - Running pre-action listener: _resource_record_pre_action 11:22:10 INFO - Running main action method: clobber 11:22:10 INFO - rmtree: /builds/slave/test/build 11:22:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:22:10 INFO - Running post-action listener: _resource_record_post_action 11:22:10 INFO - [mozharness: 2016-08-06 18:22:10.672038Z] Finished clobber step (success) 11:22:10 INFO - [mozharness: 2016-08-06 18:22:10.672139Z] Running read-buildbot-config step. 11:22:10 INFO - Running pre-action listener: _resource_record_pre_action 11:22:10 INFO - Running main action method: read_buildbot_config 11:22:10 INFO - Using buildbot properties: 11:22:10 INFO - { 11:22:10 INFO - "project": "", 11:22:10 INFO - "product": "firefox", 11:22:10 INFO - "script_repo_revision": "production", 11:22:10 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 11:22:10 INFO - "repository": "", 11:22:10 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 11:22:10 INFO - "buildid": "20160806083007", 11:22:10 INFO - "pgo_build": "True", 11:22:10 INFO - "basedir": "/builds/slave/test", 11:22:10 INFO - "buildnumber": 1437, 11:22:10 INFO - "slavename": "talos-linux64-ix-019", 11:22:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:22:10 INFO - "platform": "ubuntu64_hw", 11:22:10 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 11:22:10 INFO - "branch": "fx-team", 11:22:10 INFO - "repo_path": "integration/fx-team", 11:22:10 INFO - "revision": "fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96", 11:22:10 INFO - "stage_platform": "linux64-pgo", 11:22:10 INFO - "builduid": "ce654325fd60496c989bb2f44ace56ba", 11:22:10 INFO - "slavebuilddir": "test" 11:22:10 INFO - } 11:22:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 11:22:10 INFO - Running post-action listener: _resource_record_post_action 11:22:10 INFO - [mozharness: 2016-08-06 18:22:10.674578Z] Finished read-buildbot-config step (success) 11:22:10 INFO - [mozharness: 2016-08-06 18:22:10.674667Z] Running download-and-extract step. 11:22:10 INFO - Running pre-action listener: _resource_record_pre_action 11:22:10 INFO - Running main action method: download_and_extract 11:22:10 INFO - mkdir: /builds/slave/test/build/tests 11:22:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:10 INFO - trying https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 11:22:10 INFO - Downloading https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:11 INFO - Downloaded 1361 bytes. 11:22:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 11:22:11 INFO - Using the following test package requirements: 11:22:11 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 11:22:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:22:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:22:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'jsshell-linux-x86_64.zip'], 11:22:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:22:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 11:22:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:22:11 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:22:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:22:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:22:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:22:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:11 INFO - trying https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 11:22:11 INFO - Downloading https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:12 INFO - Downloaded 21825937 bytes. 11:22:12 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'] 11:22:12 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 11:22:13 INFO - Return code: 0 11:22:13 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 11: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')]} 11:22:13 INFO - trying https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 11:22:13 INFO - Downloading https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:14 INFO - Downloaded 21825937 bytes. 11:22:14 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'] 11:22:14 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 11:22:15 INFO - Return code: 0 11: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')]} 11:22:15 INFO - trying https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 11:22:15 INFO - Downloading https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:16 INFO - Downloaded 14413723 bytes. 11:22:16 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'] 11:22:16 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 11:22:17 INFO - Return code: 0 11:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:17 INFO - trying https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:22:17 INFO - Downloading https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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 11:22:18 INFO - Downloaded 59980642 bytes. 11:22:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:22:18 INFO - mkdir: /builds/slave/test/properties 11:22:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:22:18 INFO - Writing to file /builds/slave/test/properties/build_url 11:22:18 INFO - Contents: 11:22:18 INFO - build_url:https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:22:19 INFO - Running post-action listener: _resource_record_post_action 11:22:19 INFO - Running post-action listener: set_extra_try_arguments 11:22:19 INFO - [mozharness: 2016-08-06 18:22:19.167009Z] Finished download-and-extract step (success) 11:22:19 INFO - [mozharness: 2016-08-06 18:22:19.167331Z] Running populate-webroot step. 11:22:19 INFO - Running pre-action listener: _resource_record_pre_action 11:22:19 INFO - Running main action method: populate_webroot 11:22:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:22:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:22:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:22:19 INFO - '--tppagecycles', 11:22:19 INFO - '7'], 11:22:19 INFO - 'tests': ['tsvgm']}}, 11:22:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:22:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:22:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:22:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:22:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:22:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:22:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['damp', 'tps']}, 11:22:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'tests': ['damp', 'tps']}, 11:22:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['dromaeo_dom']}, 11:22:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:22:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['basic_compositor_video']}, 11:22:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:22:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['a11yr', 11:22:19 INFO - 'ts_paint', 11:22:19 INFO - 'tpaint', 11:22:19 INFO - 'sessionrestore', 11:22:19 INFO - 'sessionrestore_no_auto_restore', 11:22:19 INFO - 'tabpaint']}, 11:22:19 INFO - 'other-e10s': {'tests': ['a11yr', 11:22:19 INFO - 'ts_paint', 11:22:19 INFO - 'tpaint', 11:22:19 INFO - 'sessionrestore', 11:22:19 INFO - 'sessionrestore_no_auto_restore', 11:22:19 INFO - 'tabpaint']}, 11:22:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:22:19 INFO - 'ts_paint', 11:22:19 INFO - 'tpaint', 11:22:19 INFO - 'sessionrestore', 11:22:19 INFO - 'sessionrestore_no_auto_restore', 11:22:19 INFO - 'tabpaint']}, 11:22:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:22:19 INFO - 'ts_paint', 11:22:19 INFO - 'tpaint', 11:22:19 INFO - 'sessionrestore', 11:22:19 INFO - 'sessionrestore_no_auto_restore', 11:22:19 INFO - 'tabpaint']}, 11:22:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['a11yr', 11:22:19 INFO - 'ts_paint', 11:22:19 INFO - 'tpaint', 11:22:19 INFO - 'sessionrestore', 11:22:19 INFO - 'sessionrestore_no_auto_restore', 11:22:19 INFO - 'tabpaint']}, 11:22:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['a11yr', 11:22:19 INFO - 'ts_paint', 11:22:19 INFO - 'tpaint', 11:22:19 INFO - 'sessionrestore', 11:22:19 INFO - 'sessionrestore_no_auto_restore', 11:22:19 INFO - 'tabpaint']}, 11:22:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['tsvgx', 11:22:19 INFO - 'tsvgr_opacity', 11:22:19 INFO - 'tart', 11:22:19 INFO - 'tscrollx', 11:22:19 INFO - 'cart']}, 11:22:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:22:19 INFO - 'tsvgr_opacity', 11:22:19 INFO - 'tart', 11:22:19 INFO - 'tscrollx', 11:22:19 INFO - 'cart']}, 11:22:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'talos_options': ['--disable-e10s'], 11:22:19 INFO - 'tests': ['tp5o']}, 11:22:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'tests': ['tp5o']}, 11:22:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'talos_options': ['--disable-e10s', 11:22:19 INFO - '--xperf_path', 11:22:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:22:19 INFO - 'tests': ['tp5n']}, 11:22:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:22:19 INFO - 'talos_options': ['--xperf_path', 11:22:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:22:19 INFO - 'tests': ['tp5n']}}, 11:22:19 INFO - 'talos.zip': {'path': '', 11:22:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:22:19 INFO - Running post-action listener: _resource_record_post_action 11:22:19 INFO - [mozharness: 2016-08-06 18:22:19.184384Z] Finished populate-webroot step (success) 11:22:19 INFO - [mozharness: 2016-08-06 18:22:19.184473Z] Running create-virtualenv step. 11:22:19 INFO - Running pre-action listener: _resource_record_pre_action 11:22:19 INFO - Running main action method: create_virtualenv 11:22:19 INFO - Creating virtualenv /builds/slave/test/build/venv 11:22:19 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 11:22:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:22:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:22:19 INFO - Using real prefix '/usr' 11:22:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:22:19 INFO - Installing distribute.............................................................................................................................................................................................done. 11:22:21 INFO - Installing pip.................done. 11:22:21 INFO - Return code: 0 11:22:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:22:21 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')]} 11:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:21 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')]} 11:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:21 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:21 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 11:22:21 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 11:22:21 INFO - Using env: {'DISPLAY': ':0', 11:22:21 INFO - 'HOME': '/home/cltbld', 11:22:21 INFO - 'LANG': 'en_US.UTF-8', 11:22:21 INFO - 'LANGUAGE': 'en_US:en', 11:22:21 INFO - 'LOGNAME': 'cltbld', 11:22:21 INFO - 'MAIL': '/var/mail/cltbld', 11:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:21 INFO - 'MOZ_NO_REMOTE': '1', 11:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:21 INFO - 'NO_EM_RESTART': '1', 11:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:21 INFO - 'PWD': '/builds/slave/test', 11:22:21 INFO - 'SHELL': '/bin/bash', 11:22:21 INFO - 'SHLVL': '1', 11:22:21 INFO - 'TERM': 'linux', 11:22:21 INFO - 'TMOUT': '86400', 11:22:21 INFO - 'USER': 'cltbld', 11:22:21 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:21 INFO - '_': '/tools/buildbot/bin/python'} 11:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:21 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 11:22:21 INFO - Cleaning up... 11:22:22 INFO - Return code: 0 11:22:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:22:22 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')]} 11:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:22 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')]} 11:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:22 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:22 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 11:22:22 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 11:22:22 INFO - Using env: {'DISPLAY': ':0', 11:22:22 INFO - 'HOME': '/home/cltbld', 11:22:22 INFO - 'LANG': 'en_US.UTF-8', 11:22:22 INFO - 'LANGUAGE': 'en_US:en', 11:22:22 INFO - 'LOGNAME': 'cltbld', 11:22:22 INFO - 'MAIL': '/var/mail/cltbld', 11:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:22 INFO - 'MOZ_NO_REMOTE': '1', 11:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:22 INFO - 'NO_EM_RESTART': '1', 11:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:22 INFO - 'PWD': '/builds/slave/test', 11:22:22 INFO - 'SHELL': '/bin/bash', 11:22:22 INFO - 'SHLVL': '1', 11:22:22 INFO - 'TERM': 'linux', 11:22:22 INFO - 'TMOUT': '86400', 11:22:22 INFO - 'USER': 'cltbld', 11:22:22 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:22 INFO - '_': '/tools/buildbot/bin/python'} 11:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:22 INFO - Downloading/unpacking psutil>=3.1.1 11:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:22:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:22:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:22:24 INFO - Installing collected packages: psutil 11:22:24 INFO - Running setup.py install for psutil 11:22:24 INFO - building 'psutil._psutil_linux' extension 11:22:24 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 11:22:24 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 11:22:24 INFO - building 'psutil._psutil_posix' extension 11:22:24 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 11:22:24 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 11:22:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:22:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:22:24 INFO - Successfully installed psutil 11:22:24 INFO - Cleaning up... 11:22:24 INFO - Return code: 0 11:22:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:24 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:24 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 11:22:24 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 11:22:24 INFO - Using env: {'DISPLAY': ':0', 11:22:24 INFO - 'HOME': '/home/cltbld', 11:22:24 INFO - 'LANG': 'en_US.UTF-8', 11:22:24 INFO - 'LANGUAGE': 'en_US:en', 11:22:24 INFO - 'LOGNAME': 'cltbld', 11:22:24 INFO - 'MAIL': '/var/mail/cltbld', 11:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:24 INFO - 'MOZ_NO_REMOTE': '1', 11:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:24 INFO - 'NO_EM_RESTART': '1', 11:22:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:24 INFO - 'PWD': '/builds/slave/test', 11:22:24 INFO - 'SHELL': '/bin/bash', 11:22:24 INFO - 'SHLVL': '1', 11:22:24 INFO - 'TERM': 'linux', 11:22:24 INFO - 'TMOUT': '86400', 11:22:24 INFO - 'USER': 'cltbld', 11:22:24 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:24 INFO - '_': '/tools/buildbot/bin/python'} 11:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:22:24 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 11:22:24 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 11:22:24 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 11:22:24 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 11:22:24 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 11:22:24 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 11:22:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:22:26 INFO - Installing collected packages: mozsystemmonitor 11:22:26 INFO - Running setup.py install for mozsystemmonitor 11:22:26 INFO - Successfully installed mozsystemmonitor 11:22:26 INFO - Cleaning up... 11:22:27 INFO - Return code: 0 11:22:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:27 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:27 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 11:22:27 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 11:22:27 INFO - Using env: {'DISPLAY': ':0', 11:22:27 INFO - 'HOME': '/home/cltbld', 11:22:27 INFO - 'LANG': 'en_US.UTF-8', 11:22:27 INFO - 'LANGUAGE': 'en_US:en', 11:22:27 INFO - 'LOGNAME': 'cltbld', 11:22:27 INFO - 'MAIL': '/var/mail/cltbld', 11:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:27 INFO - 'MOZ_NO_REMOTE': '1', 11:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:27 INFO - 'NO_EM_RESTART': '1', 11:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:27 INFO - 'PWD': '/builds/slave/test', 11:22:27 INFO - 'SHELL': '/bin/bash', 11:22:27 INFO - 'SHLVL': '1', 11:22:27 INFO - 'TERM': 'linux', 11:22:27 INFO - 'TMOUT': '86400', 11:22:27 INFO - 'USER': 'cltbld', 11:22:27 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:27 INFO - '_': '/tools/buildbot/bin/python'} 11:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:27 INFO - Downloading/unpacking jsonschema==2.5.1 11:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:22:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11: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 11: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 11: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 11: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 11: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 11: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 11:22:29 INFO - Downloading functools32-3.2.3-2.tar.gz 11:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:22:29 INFO - warning: no files found matching '*.txt' 11:22:29 INFO - no previously-included directories found matching 'build' 11:22:29 INFO - no previously-included directories found matching 'dist' 11:22:29 INFO - no previously-included directories found matching '.git*' 11:22:29 INFO - Installing collected packages: jsonschema, functools32 11:22:29 INFO - Running setup.py install for functools32 11:22:29 INFO - warning: no files found matching '*.txt' 11:22:29 INFO - no previously-included directories found matching 'build' 11:22:29 INFO - no previously-included directories found matching 'dist' 11:22:29 INFO - no previously-included directories found matching '.git*' 11:22:29 INFO - Successfully installed jsonschema functools32 11:22:29 INFO - Cleaning up... 11:22:29 INFO - Return code: 0 11:22:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:29 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:29 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 11:22:29 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 11:22:29 INFO - Using env: {'DISPLAY': ':0', 11:22:29 INFO - 'HOME': '/home/cltbld', 11:22:29 INFO - 'LANG': 'en_US.UTF-8', 11:22:29 INFO - 'LANGUAGE': 'en_US:en', 11:22:29 INFO - 'LOGNAME': 'cltbld', 11:22:29 INFO - 'MAIL': '/var/mail/cltbld', 11:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:29 INFO - 'MOZ_NO_REMOTE': '1', 11:22:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:29 INFO - 'NO_EM_RESTART': '1', 11:22:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:29 INFO - 'PWD': '/builds/slave/test', 11:22:29 INFO - 'SHELL': '/bin/bash', 11:22:29 INFO - 'SHLVL': '1', 11:22:29 INFO - 'TERM': 'linux', 11:22:29 INFO - 'TMOUT': '86400', 11:22:29 INFO - 'USER': 'cltbld', 11:22:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:29 INFO - '_': '/tools/buildbot/bin/python'} 11:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:22:29 INFO - Cleaning up... 11:22:29 INFO - Return code: 0 11:22:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:29 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:29 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 11:22:29 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 11:22:29 INFO - Using env: {'DISPLAY': ':0', 11:22:29 INFO - 'HOME': '/home/cltbld', 11:22:29 INFO - 'LANG': 'en_US.UTF-8', 11:22:29 INFO - 'LANGUAGE': 'en_US:en', 11:22:29 INFO - 'LOGNAME': 'cltbld', 11:22:29 INFO - 'MAIL': '/var/mail/cltbld', 11:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:29 INFO - 'MOZ_NO_REMOTE': '1', 11:22:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:29 INFO - 'NO_EM_RESTART': '1', 11:22:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:29 INFO - 'PWD': '/builds/slave/test', 11:22:29 INFO - 'SHELL': '/bin/bash', 11:22:29 INFO - 'SHLVL': '1', 11:22:29 INFO - 'TERM': 'linux', 11:22:29 INFO - 'TMOUT': '86400', 11:22:29 INFO - 'USER': 'cltbld', 11:22:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:29 INFO - '_': '/tools/buildbot/bin/python'} 11:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:30 INFO - Downloading/unpacking blobuploader==1.2.4 11:22:30 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 11:22:30 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 11:22:30 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 11:22:30 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 11:22:30 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 11:22:30 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 11:22:31 INFO - Downloading blobuploader-1.2.4.tar.gz 11:22:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:22:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:22:31 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 11:22:31 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 11:22:31 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 11:22:31 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 11:22:31 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 11:22:31 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 11:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:22:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:22:32 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 11:22:32 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 11:22:32 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 11:22:32 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 11:22:32 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 11:22:32 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 11:22:32 INFO - Downloading docopt-0.6.1.tar.gz 11:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:22:32 INFO - Installing collected packages: blobuploader, requests, docopt 11:22:32 INFO - Running setup.py install for blobuploader 11:22:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:22:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:22:32 INFO - Running setup.py install for requests 11:22:33 INFO - Running setup.py install for docopt 11:22:33 INFO - Successfully installed blobuploader requests docopt 11:22:33 INFO - Cleaning up... 11:22:33 INFO - Return code: 0 11:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:33 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:33 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 11:22:33 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 11:22:33 INFO - Using env: {'DISPLAY': ':0', 11:22:33 INFO - 'HOME': '/home/cltbld', 11:22:33 INFO - 'LANG': 'en_US.UTF-8', 11:22:33 INFO - 'LANGUAGE': 'en_US:en', 11:22:33 INFO - 'LOGNAME': 'cltbld', 11:22:33 INFO - 'MAIL': '/var/mail/cltbld', 11:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:33 INFO - 'MOZ_NO_REMOTE': '1', 11:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:33 INFO - 'NO_EM_RESTART': '1', 11:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:33 INFO - 'PWD': '/builds/slave/test', 11:22:33 INFO - 'SHELL': '/bin/bash', 11:22:33 INFO - 'SHLVL': '1', 11:22:33 INFO - 'TERM': 'linux', 11:22:33 INFO - 'TMOUT': '86400', 11:22:33 INFO - 'USER': 'cltbld', 11:22:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:33 INFO - '_': '/tools/buildbot/bin/python'} 11:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:22:33 INFO - Running setup.py (path:/tmp/pip-XHHLkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:22:33 INFO - Running setup.py (path:/tmp/pip-s9zj3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:22:33 INFO - Running setup.py (path:/tmp/pip-QlL22i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:22:33 INFO - Running setup.py (path:/tmp/pip-JL4Z_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:22:33 INFO - Running setup.py (path:/tmp/pip-SzdRDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:22:33 INFO - Running setup.py (path:/tmp/pip-h0kj8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:22:33 INFO - Running setup.py (path:/tmp/pip-LjaEpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:22:33 INFO - Running setup.py (path:/tmp/pip-mFjL4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:22:34 INFO - Running setup.py (path:/tmp/pip-CBdON4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:22:34 INFO - Running setup.py (path:/tmp/pip-EnoeTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:22:34 INFO - Running setup.py (path:/tmp/pip-adgb6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:22:34 INFO - Running setup.py (path:/tmp/pip-DEU6nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:22:34 INFO - Running setup.py (path:/tmp/pip-e0BdVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:22:34 INFO - Running setup.py (path:/tmp/pip-9BfywN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:34 INFO - Running setup.py (path:/tmp/pip-qgi__4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:22:34 INFO - Running setup.py (path:/tmp/pip-SjiuGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:22:34 INFO - Running setup.py (path:/tmp/pip-S7W6pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:22:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:22:34 INFO - Running setup.py install for manifestparser 11:22:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:22:34 INFO - Running setup.py install for mozcrash 11:22:34 INFO - Running setup.py install for mozdebug 11:22:34 INFO - Running setup.py install for mozdevice 11:22:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Running setup.py install for mozfile 11:22:35 INFO - Running setup.py install for mozhttpd 11:22:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Running setup.py install for mozinfo 11:22:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Running setup.py install for mozInstall 11:22:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Running setup.py install for mozleak 11:22:35 INFO - Running setup.py install for mozlog 11:22:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Running setup.py install for moznetwork 11:22:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:22:35 INFO - Running setup.py install for mozprocess 11:22:35 INFO - Running setup.py install for mozprofile 11:22:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:22:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:22:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:22:36 INFO - Running setup.py install for mozrunner 11:22:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:22:36 INFO - Running setup.py install for mozscreenshot 11:22:36 INFO - Running setup.py install for moztest 11:22:36 INFO - Running setup.py install for mozversion 11:22:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:22:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:22:36 INFO - Cleaning up... 11:22:36 INFO - Return code: 0 11:22:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:36 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:36 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 11:22:36 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 11:22:36 INFO - Using env: {'DISPLAY': ':0', 11:22:36 INFO - 'HOME': '/home/cltbld', 11:22:36 INFO - 'LANG': 'en_US.UTF-8', 11:22:36 INFO - 'LANGUAGE': 'en_US:en', 11:22:36 INFO - 'LOGNAME': 'cltbld', 11:22:36 INFO - 'MAIL': '/var/mail/cltbld', 11:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:36 INFO - 'MOZ_NO_REMOTE': '1', 11:22:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:36 INFO - 'NO_EM_RESTART': '1', 11:22:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:36 INFO - 'PWD': '/builds/slave/test', 11:22:36 INFO - 'SHELL': '/bin/bash', 11:22:36 INFO - 'SHLVL': '1', 11:22:36 INFO - 'TERM': 'linux', 11:22:36 INFO - 'TMOUT': '86400', 11:22:36 INFO - 'USER': 'cltbld', 11:22:36 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:36 INFO - '_': '/tools/buildbot/bin/python'} 11:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:22:36 INFO - Running setup.py (path:/tmp/pip-STACZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:22:36 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)) 11:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:22:36 INFO - Running setup.py (path:/tmp/pip-0bXv_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:22:36 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)) 11:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:22:36 INFO - Running setup.py (path:/tmp/pip-8vnlvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:22:36 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)) 11:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:22:36 INFO - Running setup.py (path:/tmp/pip-u4tlvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:22:36 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)) 11:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:22:36 INFO - Running setup.py (path:/tmp/pip-Vlkp6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:22:37 INFO - Running setup.py (path:/tmp/pip-_XUdfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:22:37 INFO - Running setup.py (path:/tmp/pip-oScSoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:22:37 INFO - Running setup.py (path:/tmp/pip-ROOlCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:22:37 INFO - Running setup.py (path:/tmp/pip-En2eXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:22:37 INFO - Running setup.py (path:/tmp/pip-f30eWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:22:37 INFO - Running setup.py (path:/tmp/pip-o8vFb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:22:37 INFO - Running setup.py (path:/tmp/pip-OufxPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:22:37 INFO - Running setup.py (path:/tmp/pip-6pjoOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:22:37 INFO - Running setup.py (path:/tmp/pip-CbkOHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:37 INFO - Running setup.py (path:/tmp/pip-izeHGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:22:37 INFO - Running setup.py (path:/tmp/pip-WclxqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:22:37 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)) 11:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:22:37 INFO - Running setup.py (path:/tmp/pip-lbFAVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:22:37 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)) 11:22:37 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)) 11:22:37 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)) 11:22:37 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)) 11:22:37 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)) 11:22:37 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)) 11:22:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:22:37 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 11:22:37 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 11:22:37 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 11:22:37 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 11:22:37 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 11:22:37 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 11:22:39 INFO - Downloading blessings-1.6.tar.gz 11:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:22:39 INFO - Installing collected packages: blessings 11:22:39 INFO - Running setup.py install for blessings 11:22:39 INFO - Successfully installed blessings 11:22:39 INFO - Cleaning up... 11:22:40 INFO - Return code: 0 11:22:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:22:40 INFO - Reading from file tmpfile_stdout 11:22:40 INFO - Current package versions: 11:22:40 INFO - argparse == 1.2.1 11:22:40 INFO - blessings == 1.6 11:22:40 INFO - blobuploader == 1.2.4 11:22:40 INFO - docopt == 0.6.1 11:22:40 INFO - functools32 == 3.2.3-2 11:22:40 INFO - jsonschema == 2.5.1 11:22:40 INFO - manifestparser == 1.1 11:22:40 INFO - mozInstall == 1.12 11:22:40 INFO - mozcrash == 0.17 11:22:40 INFO - mozdebug == 0.1 11:22:40 INFO - mozdevice == 0.48 11:22:40 INFO - mozfile == 1.2 11:22:40 INFO - mozhttpd == 0.7 11:22:40 INFO - mozinfo == 0.9 11:22:40 INFO - mozleak == 0.1 11:22:40 INFO - mozlog == 3.3 11:22:40 INFO - moznetwork == 0.27 11:22:40 INFO - mozprocess == 0.23 11:22:40 INFO - mozprofile == 0.28 11:22:40 INFO - mozrunner == 6.12 11:22:40 INFO - mozscreenshot == 0.1 11:22:40 INFO - mozsystemmonitor == 0.3 11:22:40 INFO - moztest == 0.7 11:22:40 INFO - mozversion == 1.4 11:22:40 INFO - psutil == 3.1.1 11:22:40 INFO - requests == 1.2.3 11:22:40 INFO - wsgiref == 0.1.2 11:22:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:22:40 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')]} 11:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:40 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')]} 11:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:40 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:40 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 11:22:40 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 11:22:40 INFO - Using env: {'DISPLAY': ':0', 11:22:40 INFO - 'HOME': '/home/cltbld', 11:22:40 INFO - 'LANG': 'en_US.UTF-8', 11:22:40 INFO - 'LANGUAGE': 'en_US:en', 11:22:40 INFO - 'LOGNAME': 'cltbld', 11:22:40 INFO - 'MAIL': '/var/mail/cltbld', 11:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:40 INFO - 'MOZ_NO_REMOTE': '1', 11:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:40 INFO - 'NO_EM_RESTART': '1', 11:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:40 INFO - 'PWD': '/builds/slave/test', 11:22:40 INFO - 'SHELL': '/bin/bash', 11:22:40 INFO - 'SHLVL': '1', 11:22:40 INFO - 'TERM': 'linux', 11:22:40 INFO - 'TMOUT': '86400', 11:22:40 INFO - 'USER': 'cltbld', 11:22:40 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:40 INFO - '_': '/tools/buildbot/bin/python'} 11:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 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)) 11:22:40 INFO - Cleaning up... 11:22:40 INFO - Return code: 0 11:22:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:22:40 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')]} 11:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:40 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')]} 11:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:40 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 0x1518030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x167b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', '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 11:22:40 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 11:22:40 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 11:22:40 INFO - Using env: {'DISPLAY': ':0', 11:22:40 INFO - 'HOME': '/home/cltbld', 11:22:40 INFO - 'LANG': 'en_US.UTF-8', 11:22:40 INFO - 'LANGUAGE': 'en_US:en', 11:22:40 INFO - 'LOGNAME': 'cltbld', 11:22:40 INFO - 'MAIL': '/var/mail/cltbld', 11:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:40 INFO - 'MOZ_NO_REMOTE': '1', 11:22:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:40 INFO - 'NO_EM_RESTART': '1', 11:22:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:40 INFO - 'PWD': '/builds/slave/test', 11:22:40 INFO - 'SHELL': '/bin/bash', 11:22:40 INFO - 'SHLVL': '1', 11:22:40 INFO - 'TERM': 'linux', 11:22:40 INFO - 'TMOUT': '86400', 11:22:40 INFO - 'USER': 'cltbld', 11:22:40 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:40 INFO - '_': '/tools/buildbot/bin/python'} 11:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:22:40 INFO - Cleaning up... 11:22:40 INFO - Return code: 0 11:22:40 INFO - Running post-action listener: _resource_record_post_action 11:22:40 INFO - Running post-action listener: _start_resource_monitoring 11:22:40 INFO - Starting resource monitoring. 11:22:40 INFO - [mozharness: 2016-08-06 18:22:40.738664Z] Finished create-virtualenv step (success) 11:22:40 INFO - [mozharness: 2016-08-06 18:22:40.739113Z] Running install step. 11:22:40 INFO - Running pre-action listener: _resource_record_pre_action 11:22:40 INFO - Running main action method: install 11:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:22:40 INFO - Reading from file tmpfile_stdout 11:22:40 INFO - Detecting whether we're running mozinstall >=1.0... 11:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:22:41 INFO - Reading from file tmpfile_stdout 11:22:41 INFO - Output received: 11:22:41 INFO - Usage: mozinstall [options] installer 11:22:41 INFO - Options: 11:22:41 INFO - -h, --help show this help message and exit 11:22:41 INFO - -d DEST, --destination=DEST 11:22:41 INFO - Directory to install application into. [default: 11:22:41 INFO - "/builds/slave/test"] 11:22:41 INFO - --app=APP Application being installed. [default: firefox] 11:22:41 INFO - mkdir: /builds/slave/test/build/application 11:22:41 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'] 11:22:41 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 11:22:58 INFO - Reading from file tmpfile_stdout 11:22:58 INFO - Output received: 11:22:58 INFO - /builds/slave/test/build/application/firefox/firefox 11:22:58 INFO - Running post-action listener: _resource_record_post_action 11:22:58 INFO - [mozharness: 2016-08-06 18:22:58.299455Z] Finished install step (success) 11:22:58 INFO - [mozharness: 2016-08-06 18:22:58.299709Z] Running run-tests step. 11:22:58 INFO - Running pre-action listener: _resource_record_pre_action 11:22:58 INFO - Running main action method: run_tests 11:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:22:58 INFO - Python 2.7.3 11:22:58 INFO - Return code: 0 11:22:58 INFO - grabbing minidump binary from tooltool 11:22:58 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')]} 11:22:58 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 0x17d73b0>, '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 0x17d82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:22:58 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 11:22:58 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 11:22:58 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 11:22:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:22:58 INFO - Return code: 0 11:22:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:22:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:22:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:22:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:22:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 11:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 11:22:58 INFO - Using env: {'DISPLAY': ':0', 11:22:58 INFO - 'HOME': '/home/cltbld', 11:22:58 INFO - 'LANG': 'en_US.UTF-8', 11:22:58 INFO - 'LANGUAGE': 'en_US:en', 11:22:58 INFO - 'LOGNAME': 'cltbld', 11:22:58 INFO - 'MAIL': '/var/mail/cltbld', 11:22:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:22:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:58 INFO - 'MOZ_NO_REMOTE': '1', 11:22:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:22:58 INFO - 'NO_EM_RESTART': '1', 11:22:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:58 INFO - 'PWD': '/builds/slave/test', 11:22:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:22:58 INFO - 'SHELL': '/bin/bash', 11:22:58 INFO - 'SHLVL': '1', 11:22:58 INFO - 'TERM': 'linux', 11:22:58 INFO - 'TMOUT': '86400', 11:22:58 INFO - 'USER': 'cltbld', 11:22:58 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386', 11:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:58 INFO - '_': '/tools/buildbot/bin/python'} 11:22:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 11:22:58 INFO - mozversion application_buildid: 20160806083007 11:22:58 INFO - mozversion application_changeset: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 11:22:58 INFO - mozversion application_display_name: Nightly 11:22:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:22:58 INFO - mozversion application_name: Firefox 11:22:58 INFO - mozversion application_remotingname: firefox 11:22:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:22:58 INFO - mozversion application_vendor: Mozilla 11:22:58 INFO - mozversion application_version: 51.0a1 11:22:58 INFO - mozversion platform_buildid: 20160806083007 11:22:58 INFO - mozversion platform_changeset: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 11:22:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:22:58 INFO - mozversion platform_version: 51.0a1 11:22:58 INFO - using testdate: 1470507778 11:22:58 INFO - actual date: 1470507778 11:22:58 INFO - starting webserver on 'localhost:51942' 11:22:58 INFO - SUITE-START | Running 2 tests 11:22:58 INFO - TEST-START | dromaeo_css 11:22:58 INFO - operating with platform_type : linux_ 11:22:58 INFO - Initialising browser for dromaeo_css test... 11:22:58 INFO - TEST-INFO | started process 4655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOBIsiW/profile http://localhost:51942/getInfo.html) 11:23:02 INFO - PROCESS | 4655 | __metrics Screen width/height:1600/1200 11:23:02 INFO - PROCESS | 4655 | colorDepth:24 11:23:02 INFO - PROCESS | 4655 | Browser inner width/height: 1024/697 11:23:02 INFO - PROCESS | 4655 | __metrics 11:23:03 INFO - PROCESS | 4655 | 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" 11:23:03 INFO - PROCESS | 4655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:23:04 INFO - TEST-INFO | 4655: exit 0 11:23:04 INFO - Browser initialized. 11:23:04 INFO - Running cycle 1/1 for dromaeo_css test... 11:23:04 INFO - TEST-INFO | started process 4739 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOBIsiW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:23:16 INFO - PROCESS | 4739 | RSS: Main: 156495872 11:23:16 INFO - PROCESS | 4739 | 11:24:08 INFO - PROCESS | 4739 | 1470507848167 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] 11:26:17 INFO - PROCESS | 4739 | Cycle 1(1): loaded http://localhost:51942/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51942/tests/dromaeo/cssquery-ext.html) 11:26:18 INFO - PROCESS | 4739 | RSS: Main: 253435904 11:26:18 INFO - PROCESS | 4739 | 11:29:20 INFO - PROCESS | 4739 | Cycle 1(1): loaded http://localhost:51942/tests/dromaeo/cssquery-ext.html (next: http://localhost:51942/tests/dromaeo/cssquery-jquery.html) 11:29:21 INFO - PROCESS | 4739 | RSS: Main: 243601408 11:29:21 INFO - PROCESS | 4739 | 11:32:23 INFO - PROCESS | 4739 | Cycle 1(1): loaded http://localhost:51942/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51942/tests/dromaeo/cssquery-mootools.html) 11:32:23 INFO - PROCESS | 4739 | RSS: Main: 184221696 11:32:23 INFO - PROCESS | 4739 | 11:35:25 INFO - PROCESS | 4739 | Cycle 1(1): loaded http://localhost:51942/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51942/tests/dromaeo/cssquery-prototype.html) 11:35:26 INFO - PROCESS | 4739 | RSS: Main: 232984576 11:35:26 INFO - PROCESS | 4739 | 11:38:29 INFO - PROCESS | 4739 | Cycle 1(1): loaded http://localhost:51942/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51942/tests/dromaeo/cssquery-yui.html) 11:38:29 INFO - PROCESS | 4739 | RSS: Main: 184963072 11:38:29 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | Cycle 1(1): loaded http://localhost:51942/tests/dromaeo/cssquery-yui.html (next: http://localhost:51942/tests/dromaeo/cssquery-dojo.html) 11:41:32 INFO - PROCESS | 4739 | RSS: Main: 247517184 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | __start_tp_report 11:41:32 INFO - PROCESS | 4739 | _x_x_mozilla_page_load 11:41:32 INFO - PROCESS | 4739 | _x_x_mozilla_page_load_details 11:41:32 INFO - PROCESS | 4739 | |i|pagename|runs| 11:41:32 INFO - PROCESS | 4739 | |0;dojo.html;3017.0496293667493;3017.456857765602;3022.1386904732144;3039.6200474940633;3071.616047994001;5077.74611269436;5106.463821298766;5205.16717325228;5283.260343551901;5363.222332761752;5252.501012403825;5306.814261500848;5333.120035194188;5427.83716488505;5439.102548079572;4752.809887604491;4799.448063472693;4841.128596852565;4867.659263851532;4877.244027175793;4860.88199714066;4863.829765958192;4889.53549412807;4906.092372911005;4907.9263811042865;4799.1601469743;4807.206810876201;4839.298301746245;4848.418189817209;4899.044686286175;4704.764761761932;4704.811807527695;4726.858194254143;4756.7859446325;4782.641301902371;4671.906561868777;4673.696209746389;4676.485586585472;4746.02706445178;4770.189067858472;5181.533661970403;5191.844244672666;5219.112750832368;5243.187305967581;5258.894822103536;5483.75323110457;5556.249906262643;5636.464535869086;5653.971730141364;5673.035583950738;5725.227094342252;5757.827265182052;5759.452851979056;5763.279590051244;5775.942240577582;5347.171188465794;5354.250404943311;5357.43746906577;5359.571234301286;5362.24928510005;5569.805056822992;5574.804881829116;5610.579206559524;5640.379558248589;5675.233843431167;5405.837824865261;5454.672719636835;5468.699221542854;5469.671819690831;5469.835904922858;5448.645838020516;5477.260569823103;5515.586331025189;5517.917231241535;5524.861878453071;5285.101532739443;5526.143447765603;5603.859903502445;5628.324601047819;5693.743781529841;5655.123455864347;5660.29246344207;5661.830145095653;5662.065759149694;5664.631798933138;4320.179165958458;4371.082072764755;4373.75944323059;4382.6055654991205;4414.1833760753725;3738.7569807962395;3751.662350388478;3783.7162212834146;3812.3519001769764;3848.6536211741077;5249.291345668286;5251.36983561975;5254.6847189168775;5259.132243179909;5312.893742125136;5549.500544950974;5556.666600004012;5558.388577256498;5561.749721262472;5586.050371436865;5186.222066690027;5239.528442440199;5297;5303.920441193308;5333.093374126408;4872.78072486739;4877;4886.3403440535785;4889.339939108246;4912.066707325596;5791.82624521265;5819.243498345188;5822.155787410802;5834.474897259267;5836.474717275466;5367.812126575551;5586.748596313095;5652.039153343859;5661.490465858093;5748.281464816898;5890.352061273171;5902.734376952962;5903.527717782653;5905.4685078343155;5906.084556893688;5591.384947655836;5591.9720401397735;5593.8321850344555;5596.272484576979;5597.608167428241;5438.537724293479;5505;5540.612157148961;5572.526335261548;5650.3219613646625;5435.266239057677;5468.972655136698;5469.316335458067;5475.151351540518;5478.561715062865;5388.919166212589;5468.671879687232;5468.835934921959;5486.259354987185;5514.062609356339;5239.161734122522;5348.679079255257;5401.405845357086;5429.728513574384;5495.0658388073325;5529.032419326521;5532.170174473861;5569.832905012856;5582.748776305158;5594.02104631696;5404.8108316208745;5417.539509141767;5422.891542169214;5423.240746295601;5435.891282174413 11:41:32 INFO - PROCESS | 4739 | |1;ext.html;606.7379849912184;607.7690477618504;631.810456862941;632.6899819088645;650.7364517370467;43746.78126609366;45011.77494112533;45170;45189.0962180757;48940.2658960115;45840.08319833601;46323.76838115813;46332.3050154248;46355.30467042997;46382.76808615952;8154.8776768348525;9026.187643112102;9159.129882661136;9163.35856490046;9174.036726143759;10502.789944201111;10859;10870.673879783579;11286.548538058469;11301.378424186667;11085.889141108566;11130.610428635;11146.21976461648;11156.16328775341;11157.497912593954;10922.836157457645;10927.726806829813;10954.616588419447;10969.177311701655;10976.231663783537;10679.519421625968;10748.408837513934;10758.838617420744;10762;10763.569457221702;43549.78225108886;43836.3424548632;43904;43910.12179756419;43980;2990.1627544287417;3030.0909727082008;3095.736862366679;3123.703248191418;3151.495760678303;33237.66762332394;33794.49308260378;34473.655263447545;34616.30767384663;35139.472907906405;4373.475182978032;4416.425864637608;4445.688801783876;4448.844290449819;4606.470255920579;359342;360646;361807;362239;363393.1830340858;62253;62807;62827;62861.37138628647;62939.111826645276;63656.68171659112;63727.308632593835;63853.68073159697;63953.36046639474;64139.35860641334;15456.38174473031;15520.92239538795;15589.298481568356;15615.609609759847;15660.92169539145;11350.31898086101;13214.207147571176;13476;13503.797443038362;13565.59303220905;18754.24983000692;18786.624267514846;18807;18854.905725471286;18897.244110235435;6074.574779765374;6145.262568491809;6192.318844927055;6258.812235632939;6558.967205163944;955.498363359228;971.4317124482116;984.7931934293879;992.4888682328607;1081.3133660125877;32415.837920810245;32480.83759581187;32650.755401797614;32789.344213115604;33107.66892331094;1035.6789395287485;1084.3403513143141;1134.551852018448;1149.3219000789575;1187.2342339191173;16404.425845095364;17579.912100439673;17930.372436964644;18186.907386025567;18268.543286417946;508152;511407;512276;514388;516348;60728.69635651882;60826;61199;61234.69382653058;61249;1265.8227848101274;1394.539801865382;1426.165693069566;1476.1290838405391;1544.4362807575153;30495.695043050175;34347.080498157935;34355.312893742484;34821.30357392787;34978.65021349856;53333.7333313331;54349.45650543602;54374.7281263607;54380.456195439125;54384.45615543794;481580;485912;487113;487783;489832;44188.55811441845;44588;45318.773406134074;46231;48730.26904596363;49521;49686.25470618016;49987.25019124789;50131.49868501268;50770.74614626903;42097.789511053474;42292.36561451642;42835;42877.356839648055;44279.11441771082;41718;41884.79057604692;42805.57194428141;43420.56579434165;43856.56143438525;41870.58129418667;42104.3684344729;43475.56524434715;43514;43615.12769744651;34624.82687586546;37612.93128877938;37880.43179352367;38412.80793596014;38641.6135838638;28541.714582854736;28659.14022579326;28679.569806452502;28832.711672883004;28834.567481488215 11:41:32 INFO - PROCESS | 4739 | |2;jquery.html;378.9791561464122;395.5925396841251;400.89376315276456;401.45201799543634;418.52470043098094;15110;19802.207911683516;19807.702884456743;19932.302369417073;19958.90020549897;19026.90486547565;19115.426537203904;19262.614747705036;19388;19457.41627751166;5556.555475561966;5567.387587365397;5692.288463942007;5739.110437882135;5814.883702325972;5649;5726.198332233491;5731.541476681856;5731.770729170828;5732.31212254528;5701.401352857953;5712.8857422851725;7176.641167941582;7493.325600695936;7549.245075492462;7262.600556969364;7350.522216055966;7454.291842274975;7501.549907005598;7520.586367749772;7270.927290727078;7287.271272872724;7289.489735718502;7289.708411663527;7289.781306560759;27157.864210679018;27934.301642458897;28041;28272.575911361346;28273.43453130926;2444.9388765280833;2445.1808644104244;2482.4786794773117;2531.1520640585427;2592.8703564821685;15481;16345.83654163455;16531.421400250932;16735.83264167355;17264.223109960083;2152.9354119376444;2277.7722227777253;2361.4804742956694;2424.502976889751;2425.7331693513697;61590.69204653948;75470.24529754742;84875.15124848734;87660.56169719111;102497.99999999926;9448.433094014381;9870.753231169134;9901.950490247502;9979.550920208609;10083.546240419199;13307.534236301877;13407;13436.731265374638;13438.529651462151;13448.193108413527;7948.403869709795;7955.124936257007;8008.119106898236;8032.1164671886045;8100.959495202486;7871.291583757375;8078.555679437687;8087.231712987257;8254.669813207525;8265.338772898154;4041.1109555897656;4136.958630413657;4144.254034273859;4221.176870510282;4234.576542345802;1940.0590713503939;1979.881207127577;1981.197614965941;2008.6585280502056;2048.8463365247667;365.17834871539037;367.368126821866;372.5622393687461;399.09405649176205;1017.6489111256752;11155.665330040112;11360;11431.428428578538;11587.594434194763;11662.94168529152;396.6113209055142;418.74875074955395;423.53834320590704;429.38812192626006;435.3100335967464;5979.225984384735;6075.817725468243;6079.422454866781;6165.1985241919165;6174.9073763893575;514513;516927;522308;528120;536446;98178.50910745544;98272;99511;101397;102724;441.2763068567552;447.583747115186;449.3327408797932;449.6245634894834;449.8380582990121;10457.790844182922;10502.474876256309;10608.257421980386;10645;10898.07366373867;40873.182536349705;42138.36792448177;42700.78649606732;43693.78153109214;43753.78123109364;60764.657943813516;60844.6957765226;60906;62174.689126554076;62377.37622623716;8337.95831020841;8360.414770966217;8409;8478.32173426112;8790.560471976247;10440.895591044296;13200.933995329962;13579.796303055264;13595.252261125734;13707.451701931812;10430.426326552113;10538.8419173714;11606.187566870238;11688.766224675288;11854.703632409259;10698.251122421356;10960.725981850199;11032.558697652325;11357.261777984404;11449.713757156138;6126.632402055712;9046.823912891283;10595.205359598214;11869.525218991144;12208.328541929925;8398.41211115216;8479.915200847912;8539.207294009884;8609.48343099416;8795.164459376349;10994.450277486254;11964.58123965657;12323.50705971751;12364.134510584172;12796.872031279569 11:41:32 INFO - PROCESS | 4739 | |3;mootools.html;1308.0255209868649;1465.3625672832318;1525.4355888321322;1649.5628658405524;1788.2131861980736;8174.550399728013;8536.189062039111;8565.35759818013;8701.651933922636;8727.5636218189;8641.049484556694;8739.781505462352;8742.300615950724;8750.21248087672;8774.736757897273;2345.698137533668;2369.8815059246967;2370.277065495023;2372.6796882420826;2372.79831214347;2335.4745182333922;2373.442241073355;2376.025829409942;2377.7265614454386;2383.2373640435067;2316.073570571776;2324.395657129142;2339.4268404240984;2355.858648481088;2359.563480756057;2245.079517397859;2266.761989991052;2266.9433264168447;2269.3645779181857;2317.779810917977;2282.2126366403563;2287.7712228777154;2288.9542209155725;2290.484640955788;2293.185918998765;8719.912800872036;8763.12368763125;8788;8869.55652217393;8874.334424918157;5202.297689811865;5398.865028374283;5399.055165346088;5403.891922161535;5409.026375252453;3400.1669590950373;3453.205762674599;3453.689167974894;3484.362349695639;3486.7210623150086;1156.8727439981594;1161.4483120517687;1168.6844551971005;1202.9218100823507;1207.8550573931097;12344.197627154206;12454.128211025232;12507.874921250763;12528.248305101723;12535.498580056877;4316.827326906889;4542.409486766699;4614.838480653161;4639.652026098056;4643.930341044887;9806.509674516246;9813.9018609813;9824.361416507903;9829.852552211723;9830.361026533252;5280.049591073567;5280.339957505312;5280.867978300574;5290.074237008508;5296.735163241822;4359.585839345257;4690.53094690535;4800.495947925488;4854.708717476963;4956.330895329084;3628.1111127773866;3725.683316918038;3777.773333599993;3815.3323168445413;3820.2359528094494;1144.553624086607;1149.0922171484433;1149.4195431307137;1167.235460773195;1174.1546086817477;779.8908152858604;832.7501749475133;898.2364989758653;923.5290002098843;971.7424882406168;5681.829545113653;5764.740586673526;5781.132830075522;5785.942140578541;6104.633721976696;442.2040327410712;442.85828534868534;454.69535411274524;458.7385190441483;460.7051487048292;3924.274009308287;3993.0815912340327;4055.918881622351;4094.713370064067;4106.240345536085;10963.83554246687;11259.099272058216;11320.547178112784;11336.149788765875;11344.149188810872;11200.495977681025;11210.27133236337;11224.55101795919;11252.887471125185;11281.097512199005;7436.442266830009;8361.498310101413;8366;8434.325253979667;8436.915630843612;6055.031195008777;6089.48239399656;6177.6911154443;6215.409536094064;6320.178376810985;10407.531661075422;10408.47957602132;10439.060484556427;10458.320209186379;10510;10138.645147419806;10420.426876521864;10514.369137851754;10620.256582039183;10630.415327157081;7093.1488221413765;7834.4907581007055;8017.959910200411;8074.273315401642;8259.628316725642;8914.821703566022;8985.326100542616;8991.100889910957;8992.055834137584;8994.145556172152;7250.3112204342615;7266.7093316266755;7468.402527797872;7775.300222979959;8080.555569443737;7372.262773722585;7429.628518573942;7440.813979650552;7492.662830172764;7699;6975.546589471668;7042.964785176041;7043.295670432915;7059.047028651066;7066.929330706627;5110.514501122388;5159.561437277724;5210.270562121382;5301.602379821452;5454.918176227439;5189.221616757516;5249.291345668438;5251.343582052244;5364.2490051393615;5569.888602227852 11:41:32 INFO - PROCESS | 4739 | |4;prototype.html;317.68549136355017;334.61352138280284;342.0371653794567;342.7583553594716;343.00015454948823;5065.974670126649;5151.562117220036;5191.169412893938;5203.947960520393;5262.158054711247;5177.275181474596;5180.45605211452;5207.687538747668;5209.531142197202;5217.50433708797;4138.896527586804;4144.067584793427;4149.979250103753;4154.189932963073;4167.395727619493;3914.843406263746;3940.4286378475103;4086.8773936781945;4107.363358679409;4109.630133288004;4077.4495443115097;4083.5712250213746;4089.7750623715683;4090.3025244523487;4093.69297302702;4091.079507110891;4100.466939297902;4105.527864295599;4120.196561670476;4129.483814523184;3427.7771944823508;3497.2131270464197;3587.7935069931805;3592.191756854713;3622.4023036198996;5023.572996295283;5037.773300201499;5039.77321020555;5047.722375269359;5058.822941197077;4624.745638989854;4625.953740462586;4636.930446043312;4638.652101092398;4644.373009643688;5258.316418865561;5348.438413966516;5369.650972686802;5389.8113566025;5394.541463975567;3980.2735126438483;3995.62041606047;4019.618136277049;4022.1352409231863;4023.0948036691802;5660.547156227533;5665.206871038058;5674.687892165929;5680.602357834953;5694.288213973254;3973.7218394712095;4031.113155105872;4038.7778672172726;4070.1249231415386;4080.8775736727944;4108.568600296926;4108.856190033334;4116.086020000714;4117.382392641128;4123.9381409278885;4685.625149987993;4687.484376718558;4699.78850951708;4706.3646407735205;4706.388169537938;5225.921611175835;5474.42518535556;5485.6434331768305;5517.393086760453;5518.944810551923;1401.70564181521;1408.401429392504;1418.7375335562954;1419.2477986667084;1420.3821337718125;878.7846977490556;912.0788004115758;918.8392031394479;926.346925417579;929.5584597316356;4636.84078980255;4660.53394660538;4701.882452938704;4705.364775755298;4740.383750112533;5327.467253274642;5425.945740542544;5542.889142217133;5618.578606604439;5652.378238393773;5309.2036194570355;5319.148936170272;5335.813246536352;5337.2794672719465;5347.625666203407;2838.929026774347;2957.275467510474;2965.4958657028355;2971.182924695726;2976.5683975823817;5791.957447659462;5796.82609521715;5803.245578074823;5805.796797112081;5809.767609295581;5699.344575373802;5708.744076303206;5716.5140963017775;5722.828315150552;5724.198612194296;5665.235193248858;5732.971335143297;5805.403514033675;5813.906993482258;5815.244018277682;5504.174373843795;5558.305211848519;5603.859903502445;5621.044422448275;5629.043062679272;5315.6611091119785;5496.725163741877;5507.586930980113;5531.280933478622;5531.474509921551;5747.339056008613;5760.567957403296;5765.192872997867;5786.132080188006;5789.423706450589;3994.7203695742182;4031.8992025199605;4059.0258337998075;4069.348904175318;4081.469408976814;5471.534919531725;5557.638753481011;5560.721034162246;5641.843422098542;5645.350784659817;5342.599305052059;5387;5389.326334208224;5408.9459105410015;5444.972775136099;5375.354957405137;5421.376541697755;5440.945590544044;5445.618806683653;5458.608054945963;5169.767360468863;5248.186531087687;5320;5351.892962140813;5383.246345511553;5336.345732822837;5350.34139416782;5444.237806707143;5449.863753406196;5473.8357849264585;4834.516548345137;5116.769745361393;5239.764210610456;5244.954467757501;5248.08158572256 11:41:32 INFO - PROCESS | 4739 | |5;yui.html;734.7134617499177;744.6909532543995;746.3208480282943;790.5928446849872;825.7894236969576;14972.475963341285;15596.610084747887;15799.526014219564;15812.44656437025;15832.445864394735;13366.732665346688;14355.282235888217;14486.782698259534;14514.419423223086;14558.781618275709;777.8716511775565;797.1908512859452;807.7415227127321;808.6967387229789;809.251442415766;807.4549678966704;809.9959500202491;810.2990912860363;810.9148539403366;811.4238890387832;853.1127627267634;855.9058503564603;856.3834039491556;857.4255248983166;857.4640849469082;850.2730165708354;850.8681154421074;851.4465597361702;853.8548446764065;853.8847255620504;617.1606615003592;618.8112625649176;619.5013014523307;619.5260625621358;619.6870580356891;13789;13915.373808178656;14025.859741402557;14431.278436078259;14528.564143075724;13457.192568445924;13870.098443601133;13892.583222503341;13978.161310321413;14334.856651433562;11819.34993575353;11884.286942783374;11917.761644767055;12603.873961260362;12698.428570714248;3877.651011408987;3935.8032098394965;3941.4876066111224;4137.586241375869;4143.81352839123;82990;86173;87149.56425217833;87956;89700.55149724275;9985.950070249603;10091.192704583616;10154.187664986783;10199.235057370726;10531.104856087319;19573.804261957197;19662.70505942412;19671.311504097575;19700;19727.11227994715;8679.392442528962;8802.91197088021;8893.822123557622;8904.465732056098;9137.954310228539;4720.716756994591;4940.135476291634;4943.8022479101155;4958.132326842788;5043.5712964398435;3173.841307934594;3252.756043296762;3260.918477038093;3274.607047154309;3287.6054873414864;535.344203350892;553.8809156031473;559.8740283436194;570.4466667332681;577.742904407543;612.2561088277764;634.5780056262626;693.7883945396602;734.930181632744;774.8915151878741;10061.19510439163;10061.748456288651;10126.139278161292;10395.32430392022;10440.582376704999;611.3825036712952;654.3325807676239;706.6996526476227;763.7365109037373;765.6554550452319;4605.631549476034;4759.785809638574;4799.66402351832;4981.526754958273;5058.342415486037;116914;118005;118245.40877295559;119072.40463797799;119171;31765.364692706018;37503;94439;100855;102895.3697083633;54682.45317546853;72915.63542182255;90980;92813;93917.5304123475;9444.95277523608;9689.079537443931;9746.269029822934;9756.365836220622;9773.706788796348;53698.31508424641;53731.46268537421;54276;54336.45663543314;55707.44292557023;72308;77087;81170;81959;83174.58412707898;12019.459124339252;12053;12055.51777928873;12217.511299548274;12772.680682983;14125.34986005606;14343.139411634918;14368.353424096149;14498.782518262446;14510;13734;13801.723965520834;13878.306084695927;14045.719085618435;14582.343794529059;11818.704532386415;12062.51749929993;12132.454039568063;12210.633680989584;12791.232526048467;13333.66665833362;13385.732285354434;13579.049466537643;13771.586852394445;14218.715625687222;10975.341479510935;11167.385793781095;11232.775344493228;11412.543498259976;11428.885711142782;10029.398236105857;10130.89869101329;10393;11410.543578256777;11423.200375973602 11:41:32 INFO - PROCESS | 4739 | __end_tp_report 11:41:32 INFO - PROCESS | 4739 | __start_cc_report 11:41:32 INFO - PROCESS | 4739 | _x_x_mozilla_cycle_collect,945 11:41:32 INFO - PROCESS | 4739 | __end_cc_report 11:41:32 INFO - PROCESS | 4739 | __startTimestamp1470508892817__endTimestamp 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | ------- Summary: start ------- 11:41:32 INFO - PROCESS | 4739 | Number of tests: 6 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | [#0] dojo.html Cycles:165 Average:5218.08 Median:5422.89 stddev:588.54 (10.9%) stddev-sans-first:564.44 11:41:32 INFO - PROCESS | 4739 | Values: 3017.0 3017.5 3022.1 3039.6 3071.6 5077.7 5106.5 5205.2 5283.3 5363.2 5252.5 5306.8 5333.1 5427.8 5439.1 4752.8 4799.4 4841.1 4867.7 4877.2 4860.9 4863.8 4889.5 4906.1 4907.9 4799.2 4807.2 4839.3 4848.4 4899.0 4704.8 4704.8 4726.9 4756.8 4782.6 4671.9 4673.7 4676.5 4746.0 4770.2 5181.5 5191.8 5219.1 5243.2 5258.9 5483.8 5556.2 5636.5 5654.0 5673.0 5725.2 5757.8 5759.5 5763.3 5775.9 5347.2 5354.3 5357.4 5359.6 5362.2 5569.8 5574.8 5610.6 5640.4 5675.2 5405.8 5454.7 5468.7 5469.7 5469.8 5448.6 5477.3 5515.6 5517.9 5524.9 5285.1 5526.1 5603.9 5628.3 5693.7 5655.1 5660.3 5661.8 5662.1 5664.6 4320.2 4371.1 4373.8 4382.6 4414.2 3738.8 3751.7 3783.7 3812.4 3848.7 5249.3 5251.4 5254.7 5259.1 5312.9 5549.5 5556.7 5558.4 5561.7 5586.1 5186.2 5239.5 5297.0 5303.9 5333.1 4872.8 4877.0 4886.3 4889.3 4912.1 5791.8 5819.2 5822.2 5834.5 5836.5 5367.8 5586.7 5652.0 5661.5 5748.3 5890.4 5902.7 5903.5 5905.5 5906.1 5591.4 5592.0 5593.8 5596.3 5597.6 5438.5 5505.0 5540.6 5572.5 5650.3 5435.3 5469.0 5469.3 5475.2 5478.6 5388.9 5468.7 5468.8 5486.3 5514.1 5239.2 5348.7 5401.4 5429.7 5495.1 5529.0 5532.2 5569.8 5582.7 5594.0 5404.8 5417.5 5422.9 5423.2 5435.9 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | [#1] ext.html Cycles:180 Average:62915.44 Median:42198.37 stddev:121184.32 (287.2%) stddev-sans-first:121433.98 11:41:32 INFO - PROCESS | 4739 | Values: 606.7 607.8 631.8 632.7 650.7 43746.8 45011.8 45170.0 45189.1 48940.3 45840.1 46323.8 46332.3 46355.3 46382.8 8154.9 9026.2 9159.1 9163.4 9174.0 10502.8 10859.0 10870.7 11286.5 11301.4 11085.9 11130.6 11146.2 11156.2 11157.5 10922.8 10927.7 10954.6 10969.2 10976.2 10679.5 10748.4 10758.8 10762.0 10763.6 43549.8 43836.3 43904.0 43910.1 43980.0 2990.2 3030.1 3095.7 3123.7 3151.5 33237.7 33794.5 34473.7 34616.3 35139.5 4373.5 4416.4 4445.7 4448.8 4606.5 359342.0 360646.0 361807.0 362239.0 363393.2 62253.0 62807.0 62827.0 62861.4 62939.1 63656.7 63727.3 63853.7 63953.4 64139.4 15456.4 15520.9 15589.3 15615.6 15660.9 11350.3 13214.2 13476.0 13503.8 13565.6 18754.2 18786.6 18807.0 18854.9 18897.2 6074.6 6145.3 6192.3 6258.8 6559.0 955.5 971.4 984.8 992.5 1081.3 32415.8 32480.8 32650.8 32789.3 33107.7 1035.7 1084.3 1134.6 1149.3 1187.2 16404.4 17579.9 17930.4 18186.9 18268.5 508152.0 511407.0 512276.0 514388.0 516348.0 60728.7 60826.0 61199.0 61234.7 61249.0 1265.8 1394.5 1426.2 1476.1 1544.4 30495.7 34347.1 34355.3 34821.3 34978.7 53333.7 54349.5 54374.7 54380.5 54384.5 481580.0 485912.0 487113.0 487783.0 489832.0 44188.6 44588.0 45318.8 46231.0 48730.3 49521.0 49686.3 49987.3 50131.5 50770.7 42097.8 42292.4 42835.0 42877.4 44279.1 41718.0 41884.8 42805.6 43420.6 43856.6 41870.6 42104.4 43475.6 43514.0 43615.1 34624.8 37612.9 37880.4 38412.8 38641.6 28541.7 28659.1 28679.6 28832.7 28834.6 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | [#2] jquery.html Cycles:180 Average:30004.34 Median:2281.39 stddev:86520.07 (3792.4%) stddev-sans-first:86734.19 11:41:32 INFO - PROCESS | 4739 | Values: 379.0 395.6 400.9 401.5 418.5 15110.0 19802.2 19807.7 19932.3 19958.9 19026.9 19115.4 19262.6 19388.0 19457.4 5556.6 5567.4 5692.3 5739.1 5814.9 5649.0 5726.2 5731.5 5731.8 5732.3 5701.4 5712.9 7176.6 7493.3 7549.2 7262.6 7350.5 7454.3 7501.5 7520.6 7270.9 7287.3 7289.5 7289.7 7289.8 27157.9 27934.3 28041.0 28272.6 28273.4 2444.9 2445.2 2482.5 2531.2 2592.9 15481.0 16345.8 16531.4 16735.8 17264.2 2152.9 2277.8 2361.5 2424.5 2425.7 61590.7 75470.2 84875.2 87660.6 102498.0 9448.4 9870.8 9902.0 9979.6 10083.5 13307.5 13407.0 13436.7 13438.5 13448.2 7948.4 7955.1 8008.1 8032.1 8101.0 7871.3 8078.6 8087.2 8254.7 8265.3 4041.1 4137.0 4144.3 4221.2 4234.6 1940.1 1979.9 1981.2 2008.7 2048.8 365.2 367.4 372.6 399.1 1017.6 11155.7 11360.0 11431.4 11587.6 11662.9 396.6 418.7 423.5 429.4 435.3 5979.2 6075.8 6079.4 6165.2 6174.9 514513.0 516927.0 522308.0 528120.0 536446.0 98178.5 98272.0 99511.0 101397.0 102724.0 441.3 447.6 449.3 449.6 449.8 10457.8 10502.5 10608.3 10645.0 10898.1 40873.2 42138.4 42700.8 43693.8 43753.8 60764.7 60844.7 60906.0 62174.7 62377.4 8338.0 8360.4 8409.0 8478.3 8790.6 10440.9 13200.9 13579.8 13595.3 13707.5 10430.4 10538.8 11606.2 11688.8 11854.7 10698.3 10960.7 11032.6 11357.3 11449.7 6126.6 9046.8 10595.2 11869.5 12208.3 8398.4 8479.9 8539.2 8609.5 8795.2 10994.5 11964.6 12323.5 12364.1 12796.9 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | [#3] mootools.html Cycles:180 Average:5819.01 Median:4667.23 stddev:3373.21 (72.3%) stddev-sans-first:3365.64 11:41:32 INFO - PROCESS | 4739 | Values: 1308.0 1465.4 1525.4 1649.6 1788.2 8174.6 8536.2 8565.4 8701.7 8727.6 8641.0 8739.8 8742.3 8750.2 8774.7 2345.7 2369.9 2370.3 2372.7 2372.8 2335.5 2373.4 2376.0 2377.7 2383.2 2316.1 2324.4 2339.4 2355.9 2359.6 2245.1 2266.8 2266.9 2269.4 2317.8 2282.2 2287.8 2289.0 2290.5 2293.2 8719.9 8763.1 8788.0 8869.6 8874.3 5202.3 5398.9 5399.1 5403.9 5409.0 3400.2 3453.2 3453.7 3484.4 3486.7 1156.9 1161.4 1168.7 1202.9 1207.9 12344.2 12454.1 12507.9 12528.2 12535.5 4316.8 4542.4 4614.8 4639.7 4643.9 9806.5 9813.9 9824.4 9829.9 9830.4 5280.0 5280.3 5280.9 5290.1 5296.7 4359.6 4690.5 4800.5 4854.7 4956.3 3628.1 3725.7 3777.8 3815.3 3820.2 1144.6 1149.1 1149.4 1167.2 1174.2 779.9 832.8 898.2 923.5 971.7 5681.8 5764.7 5781.1 5785.9 6104.6 442.2 442.9 454.7 458.7 460.7 3924.3 3993.1 4055.9 4094.7 4106.2 10963.8 11259.1 11320.5 11336.1 11344.1 11200.5 11210.3 11224.6 11252.9 11281.1 7436.4 8361.5 8366.0 8434.3 8436.9 6055.0 6089.5 6177.7 6215.4 6320.2 10407.5 10408.5 10439.1 10458.3 10510.0 10138.6 10420.4 10514.4 10620.3 10630.4 7093.1 7834.5 8018.0 8074.3 8259.6 8914.8 8985.3 8991.1 8992.1 8994.1 7250.3 7266.7 7468.4 7775.3 8080.6 7372.3 7429.6 7440.8 7492.7 7699.0 6975.5 7043.0 7043.3 7059.0 7066.9 5110.5 5159.6 5210.3 5301.6 5454.9 5189.2 5249.3 5251.3 5364.2 5569.9 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | [#4] prototype.html Cycles:180 Average:4578.93 Median:5213.52 stddev:1333.55 (25.6%) stddev-sans-first:1298.37 11:41:32 INFO - PROCESS | 4739 | Values: 317.7 334.6 342.0 342.8 343.0 5066.0 5151.6 5191.2 5203.9 5262.2 5177.3 5180.5 5207.7 5209.5 5217.5 4138.9 4144.1 4150.0 4154.2 4167.4 3914.8 3940.4 4086.9 4107.4 4109.6 4077.4 4083.6 4089.8 4090.3 4093.7 4091.1 4100.5 4105.5 4120.2 4129.5 3427.8 3497.2 3587.8 3592.2 3622.4 5023.6 5037.8 5039.8 5047.7 5058.8 4624.7 4626.0 4636.9 4638.7 4644.4 5258.3 5348.4 5369.7 5389.8 5394.5 3980.3 3995.6 4019.6 4022.1 4023.1 5660.5 5665.2 5674.7 5680.6 5694.3 3973.7 4031.1 4038.8 4070.1 4080.9 4108.6 4108.9 4116.1 4117.4 4123.9 4685.6 4687.5 4699.8 4706.4 4706.4 5225.9 5474.4 5485.6 5517.4 5518.9 1401.7 1408.4 1418.7 1419.2 1420.4 878.8 912.1 918.8 926.3 929.6 4636.8 4660.5 4701.9 4705.4 4740.4 5327.5 5425.9 5542.9 5618.6 5652.4 5309.2 5319.1 5335.8 5337.3 5347.6 2838.9 2957.3 2965.5 2971.2 2976.6 5792.0 5796.8 5803.2 5805.8 5809.8 5699.3 5708.7 5716.5 5722.8 5724.2 5665.2 5733.0 5805.4 5813.9 5815.2 5504.2 5558.3 5603.9 5621.0 5629.0 5315.7 5496.7 5507.6 5531.3 5531.5 5747.3 5760.6 5765.2 5786.1 5789.4 3994.7 4031.9 4059.0 4069.3 4081.5 5471.5 5557.6 5560.7 5641.8 5645.4 5342.6 5387.0 5389.3 5408.9 5445.0 5375.4 5421.4 5440.9 5445.6 5458.6 5169.8 5248.2 5320.0 5351.9 5383.2 5336.3 5350.3 5444.2 5449.9 5473.8 4834.5 5116.8 5239.8 5245.0 5248.1 11:41:32 INFO - PROCESS | 4739 | 11:41:32 INFO - PROCESS | 4739 | [#5] yui.html Cycles:180 Average:20386.30 Median:4039.54 stddev:29892.07 (740.0%) stddev-sans-first:29939.50 11:41:32 INFO - PROCESS | 4739 | Values: 734.7 744.7 746.3 790.6 825.8 14972.5 15596.6 15799.5 15812.4 15832.4 13366.7 14355.3 14486.8 14514.4 14558.8 777.9 797.2 807.7 808.7 809.3 807.5 810.0 810.3 810.9 811.4 853.1 855.9 856.4 857.4 857.5 850.3 850.9 851.4 853.9 853.9 617.2 618.8 619.5 619.5 619.7 13789.0 13915.4 14025.9 14431.3 14528.6 13457.2 13870.1 13892.6 13978.2 14334.9 11819.3 11884.3 11917.8 12603.9 12698.4 3877.7 3935.8 3941.5 4137.6 4143.8 82990.0 86173.0 87149.6 87956.0 89700.6 9986.0 10091.2 10154.2 10199.2 10531.1 19573.8 19662.7 19671.3 19700.0 19727.1 8679.4 8802.9 8893.8 8904.5 9138.0 4720.7 4940.1 4943.8 4958.1 5043.6 3173.8 3252.8 3260.9 3274.6 3287.6 535.3 553.9 559.9 570.4 577.7 612.3 634.6 693.8 734.9 774.9 10061.2 10061.7 10126.1 10395.3 10440.6 611.4 654.3 706.7 763.7 765.7 4605.6 4759.8 4799.7 4981.5 5058.3 116914.0 118005.0 118245.4 119072.4 119171.0 31765.4 37503.0 94439.0 100855.0 102895.4 54682.5 72915.6 90980.0 92813.0 93917.5 9445.0 9689.1 9746.3 9756.4 9773.7 53698.3 53731.5 54276.0 54336.5 55707.4 72308.0 77087.0 81170.0 81959.0 83174.6 12019.5 12053.0 12055.5 12217.5 12772.7 14125.3 14343.1 14368.4 14498.8 14510.0 13734.0 13801.7 13878.3 14045.7 14582.3 11818.7 12062.5 12132.5 12210.6 12791.2 13333.7 13385.7 13579.0 13771.6 14218.7 10975.3 11167.4 11232.8 11412.5 11428.9 10029.4 10130.9 10393.0 11410.5 11423.2 11:41:32 INFO - PROCESS | 4739 | -------- Summary: end -------- 11:41:32 INFO - PROCESS | 4739 | 11:41:33 INFO - TEST-INFO | 4739: exit 0 11:41:33 INFO - TEST-OK | dromaeo_css | took 1115061ms 11:41:33 INFO - TEST-START | kraken 11:41:33 INFO - operating with platform_type : linux_ 11:41:33 INFO - Initialising browser for kraken test... 11:41:33 INFO - TEST-INFO | started process 4926 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ_fQKO/profile http://localhost:51942/getInfo.html) 11:41:37 INFO - PROCESS | 4926 | __metrics Screen width/height:1600/1200 11:41:37 INFO - PROCESS | 4926 | colorDepth:24 11:41:37 INFO - PROCESS | 4926 | Browser inner width/height: 1024/697 11:41:37 INFO - PROCESS | 4926 | __metrics 11:41:38 INFO - PROCESS | 4926 | 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" 11:41:38 INFO - PROCESS | 4926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:41:39 INFO - PROCESS | 4926 | console.error: PushService: 11:41:39 INFO - PROCESS | 4926 | stateChangeProcessEnqueue: Error transitioning state 11:41:39 INFO - PROCESS | 4926 | UnknownError 11:41:39 INFO - TEST-INFO | 4926: exit 0 11:41:39 INFO - Browser initialized. 11:41:39 INFO - Running cycle 1/1 for kraken test... 11:41:39 INFO - TEST-INFO | started process 5003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ_fQKO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:41:51 INFO - PROCESS | 5003 | RSS: Main: 157048832 11:41:51 INFO - PROCESS | 5003 | 11:41:54 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51942/tests/kraken/driver.html?testName=audio-beat-detection) 11:41:54 INFO - PROCESS | 5003 | RSS: Main: 213061632 11:41:54 INFO - PROCESS | 5003 | 11:41:57 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51942/tests/kraken/driver.html?testName=audio-dft) 11:41:58 INFO - PROCESS | 5003 | RSS: Main: 192499712 11:41:58 INFO - PROCESS | 5003 | 11:42:01 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51942/tests/kraken/driver.html?testName=audio-fft) 11:42:01 INFO - PROCESS | 5003 | RSS: Main: 269905920 11:42:01 INFO - PROCESS | 5003 | 11:42:04 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51942/tests/kraken/driver.html?testName=audio-oscillator) 11:42:04 INFO - PROCESS | 5003 | RSS: Main: 179384320 11:42:04 INFO - PROCESS | 5003 | 11:42:06 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51942/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:42:07 INFO - PROCESS | 5003 | RSS: Main: 184168448 11:42:07 INFO - PROCESS | 5003 | 11:42:10 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51942/tests/kraken/driver.html?testName=imaging-darkroom) 11:42:10 INFO - PROCESS | 5003 | RSS: Main: 218886144 11:42:10 INFO - PROCESS | 5003 | 11:42:14 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51942/tests/kraken/driver.html?testName=imaging-desaturate) 11:42:14 INFO - PROCESS | 5003 | RSS: Main: 220344320 11:42:14 INFO - PROCESS | 5003 | 11:42:17 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51942/tests/kraken/driver.html?testName=json-parse-financial) 11:42:18 INFO - PROCESS | 5003 | RSS: Main: 219897856 11:42:18 INFO - PROCESS | 5003 | 11:42:19 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51942/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:42:20 INFO - PROCESS | 5003 | RSS: Main: 183758848 11:42:20 INFO - PROCESS | 5003 | 11:42:22 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:42:22 INFO - PROCESS | 5003 | RSS: Main: 189300736 11:42:22 INFO - PROCESS | 5003 | 11:42:24 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:42:25 INFO - PROCESS | 5003 | RSS: Main: 194023424 11:42:25 INFO - PROCESS | 5003 | 11:42:27 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:42:28 INFO - PROCESS | 5003 | RSS: Main: 189173760 11:42:28 INFO - PROCESS | 5003 | 11:42:31 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:42:31 INFO - PROCESS | 5003 | RSS: Main: 192163840 11:42:31 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | Cycle 1(1): loaded http://localhost:51942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51942/tests/kraken/driver.html?testName=ai-astar) 11:42:33 INFO - PROCESS | 5003 | RSS: Main: 242827264 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | __start_tp_report 11:42:33 INFO - PROCESS | 5003 | _x_x_mozilla_page_load 11:42:33 INFO - PROCESS | 5003 | _x_x_mozilla_page_load_details 11:42:33 INFO - PROCESS | 5003 | |i|pagename|runs| 11:42:33 INFO - PROCESS | 5003 | |0;ai-astar;102;114;111;111;111;111;110;110;111;110 11:42:33 INFO - PROCESS | 5003 | |1;audio-beat-detection;154;118;118;184;124;124;140;119;156;118 11:42:33 INFO - PROCESS | 5003 | |2;audio-dft;161;162;160;161;160;160;160;161;160;162 11:42:33 INFO - PROCESS | 5003 | |3;audio-fft;75;76;76;101;75;76;74;74;108;75 11:42:33 INFO - PROCESS | 5003 | |4;audio-oscillator;83;83;121;84;84;84;120;84;83;133 11:42:33 INFO - PROCESS | 5003 | |5;imaging-gaussian-blur;117;117;116;118;117;117;116;117;117;117 11:42:33 INFO - PROCESS | 5003 | |6;imaging-darkroom;146;146;146;146;147;146;146;146;145;146 11:42:33 INFO - PROCESS | 5003 | |7;imaging-desaturate;95;94;95;95;95;95;95;94;96;95 11:42:33 INFO - PROCESS | 5003 | |8;json-parse-financial;51;51;52;50;51;53;52;51;50;50 11:42:33 INFO - PROCESS | 5003 | |9;json-stringify-tinderbox;59;60;60;60;61;60;60;60;61;60 11:42:33 INFO - PROCESS | 5003 | |10;stanford-crypto-aes;82;76;77;75;76;76;81;75;75;76 11:42:33 INFO - PROCESS | 5003 | |11;stanford-crypto-ccm;130;123;121;122;125;125;119;119;138;122 11:42:33 INFO - PROCESS | 5003 | |12;stanford-crypto-pbkdf2;166;158;157;156;156;158;157;156;155;158 11:42:33 INFO - PROCESS | 5003 | |13;stanford-crypto-sha256-iterative;65;66;64;63;62;63;62;62;61;65 11:42:33 INFO - PROCESS | 5003 | __end_tp_report 11:42:33 INFO - PROCESS | 5003 | __start_cc_report 11:42:33 INFO - PROCESS | 5003 | _x_x_mozilla_cycle_collect,1669 11:42:33 INFO - PROCESS | 5003 | __end_cc_report 11:42:33 INFO - PROCESS | 5003 | __startTimestamp1470508953654__endTimestamp 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | ------- Summary: start ------- 11:42:33 INFO - PROCESS | 5003 | Number of tests: 14 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:3.07 (2.8%) stddev-sans-first:1.22 11:42:33 INFO - PROCESS | 5003 | Values: 102.0 114.0 111.0 111.0 111.0 111.0 110.0 110.0 111.0 110.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:132.00 stddev:22.58 (17.1%) stddev-sans-first:22.94 11:42:33 INFO - PROCESS | 5003 | Values: 154.0 118.0 118.0 184.0 124.0 124.0 140.0 119.0 156.0 118.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 11:42:33 INFO - PROCESS | 5003 | Values: 161.0 162.0 160.0 161.0 160.0 160.0 160.0 161.0 160.0 162.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:12.52 (16.7%) stddev-sans-first:13.09 11:42:33 INFO - PROCESS | 5003 | Values: 75.0 76.0 76.0 101.0 75.0 76.0 74.0 74.0 108.0 75.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:83.50 stddev:20.15 (24.1%) stddev-sans-first:20.82 11:42:33 INFO - PROCESS | 5003 | Values: 83.0 83.0 121.0 84.0 84.0 84.0 120.0 84.0 83.0 133.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 11:42:33 INFO - PROCESS | 5003 | Values: 117.0 117.0 116.0 118.0 117.0 117.0 116.0 117.0 117.0 117.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 11:42:33 INFO - PROCESS | 5003 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 145.0 146.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 11:42:33 INFO - PROCESS | 5003 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 96.0 95.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 11:42:33 INFO - PROCESS | 5003 | Values: 51.0 51.0 52.0 50.0 51.0 53.0 52.0 51.0 50.0 50.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 11:42:33 INFO - PROCESS | 5003 | Values: 59.0 60.0 60.0 60.0 61.0 60.0 60.0 60.0 61.0 60.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.00 stddev:2.51 (3.3%) stddev-sans-first:1.87 11:42:33 INFO - PROCESS | 5003 | Values: 82.0 76.0 77.0 75.0 76.0 76.0 81.0 75.0 75.0 76.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:124.00 stddev:5.78 (4.7%) stddev-sans-first:5.76 11:42:33 INFO - PROCESS | 5003 | Values: 130.0 123.0 121.0 122.0 125.0 125.0 119.0 119.0 138.0 122.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.70 Median:157.50 stddev:3.09 (2.0%) stddev-sans-first:1.09 11:42:33 INFO - PROCESS | 5003 | Values: 166.0 158.0 157.0 156.0 156.0 158.0 157.0 156.0 155.0 158.0 11:42:33 INFO - PROCESS | 5003 | 11:42:33 INFO - PROCESS | 5003 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.64 (2.6%) stddev-sans-first:1.62 11:42:33 INFO - PROCESS | 5003 | Values: 65.0 66.0 64.0 63.0 62.0 63.0 62.0 62.0 61.0 65.0 11:42:33 INFO - PROCESS | 5003 | -------- Summary: end -------- 11:42:33 INFO - PROCESS | 5003 | 11:42:34 INFO - TEST-INFO | 5003: exit 0 11:42:34 INFO - TEST-OK | kraken | took 61031ms 11:42:34 INFO - SUITE-END | took 1176s 11:42:34 INFO - Completed test suite (00:19:36) 11:42:34 INFO - javascript benchmark 11:42:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.0496293667493, 3017.456857765602, 3022.1386904732144, 3039.6200474940633, 3071.616047994001, 5077.74611269436, 5106.463821298766, 5205.16717325228, 5283.260343551901, 5363.222332761752, 5252.501012403825, 5306.814261500848, 5333.120035194188, 5427.83716488505, 5439.102548079572, 4752.809887604491, 4799.448063472693, 4841.128596852565, 4867.659263851532, 4877.244027175793, 4860.88199714066, 4863.829765958192, 4889.53549412807, 4906.092372911005, 4907.9263811042865, 4799.1601469743, 4807.206810876201, 4839.298301746245, 4848.418189817209, 4899.044686286175, 4704.764761761932, 4704.811807527695, 4726.858194254143, 4756.7859446325, 4782.641301902371, 4671.906561868777, 4673.696209746389, 4676.485586585472, 4746.02706445178, 4770.189067858472, 5181.533661970403, 5191.844244672666, 5219.112750832368, 5243.187305967581, 5258.894822103536, 5483.75323110457, 5556.249906262643, 5636.464535869086, 5653.971730141364, 5673.035583950738, 5725.227094342252, 5757.827265182052, 5759.452851979056, 5763.279590051244, 5775.942240577582, 5347.171188465794, 5354.250404943311, 5357.43746906577, 5359.571234301286, 5362.24928510005, 5569.805056822992, 5574.804881829116, 5610.579206559524, 5640.379558248589, 5675.233843431167, 5405.837824865261, 5454.672719636835, 5468.699221542854, 5469.671819690831, 5469.835904922858, 5448.645838020516, 5477.260569823103, 5515.586331025189, 5517.917231241535, 5524.861878453071, 5285.101532739443, 5526.143447765603, 5603.859903502445, 5628.324601047819, 5693.743781529841, 5655.123455864347, 5660.29246344207, 5661.830145095653, 5662.065759149694, 5664.631798933138, 4320.179165958458, 4371.082072764755, 4373.75944323059, 4382.6055654991205, 4414.1833760753725, 3738.7569807962395, 3751.662350388478, 3783.7162212834146, 3812.3519001769764, 3848.6536211741077, 5249.291345668286, 5251.36983561975, 5254.6847189168775, 5259.132243179909, 5312.893742125136, 5549.500544950974, 5556.666600004012, 5558.388577256498, 5561.749721262472, 5586.050371436865, 5186.222066690027, 5239.528442440199, 5297.0, 5303.920441193308, 5333.093374126408, 4872.78072486739, 4877.0, 4886.3403440535785, 4889.339939108246, 4912.066707325596, 5791.82624521265, 5819.243498345188, 5822.155787410802, 5834.474897259267, 5836.474717275466, 5367.812126575551, 5586.748596313095, 5652.039153343859, 5661.490465858093, 5748.281464816898, 5890.352061273171, 5902.734376952962, 5903.527717782653, 5905.4685078343155, 5906.084556893688, 5591.384947655836, 5591.9720401397735, 5593.8321850344555, 5596.272484576979, 5597.608167428241, 5438.537724293479, 5505.0, 5540.612157148961, 5572.526335261548, 5650.3219613646625, 5435.266239057677, 5468.972655136698, 5469.316335458067, 5475.151351540518, 5478.561715062865, 5388.919166212589, 5468.671879687232, 5468.835934921959, 5486.259354987185, 5514.062609356339, 5239.161734122522, 5348.679079255257, 5401.405845357086, 5429.728513574384, 5495.0658388073325, 5529.032419326521, 5532.170174473861, 5569.832905012856, 5582.748776305158, 5594.02104631696, 5404.8108316208745, 5417.539509141767, 5422.891542169214, 5423.240746295601, 5435.891282174413], "value": 5178.434664574142, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.7379849912184, 607.7690477618504, 631.810456862941, 632.6899819088645, 650.7364517370467, 43746.78126609366, 45011.77494112533, 45170.0, 45189.0962180757, 48940.2658960115, 45840.08319833601, 46323.76838115813, 46332.3050154248, 46355.30467042997, 46382.76808615952, 8154.8776768348525, 9026.187643112102, 9159.129882661136, 9163.35856490046, 9174.036726143759, 10502.789944201111, 10859.0, 10870.673879783579, 11286.548538058469, 11301.378424186667, 11085.889141108566, 11130.610428635, 11146.21976461648, 11156.16328775341, 11157.497912593954, 10922.836157457645, 10927.726806829813, 10954.616588419447, 10969.177311701655, 10976.231663783537, 10679.519421625968, 10748.408837513934, 10758.838617420744, 10762.0, 10763.569457221702, 43549.78225108886, 43836.3424548632, 43904.0, 43910.12179756419, 43980.0, 2990.1627544287417, 3030.0909727082008, 3095.736862366679, 3123.703248191418, 3151.495760678303, 33237.66762332394, 33794.49308260378, 34473.655263447545, 34616.30767384663, 35139.472907906405, 4373.475182978032, 4416.425864637608, 4445.688801783876, 4448.844290449819, 4606.470255920579, 359342.0, 360646.0, 361807.0, 362239.0, 363393.1830340858, 62253.0, 62807.0, 62827.0, 62861.37138628647, 62939.111826645276, 63656.68171659112, 63727.308632593835, 63853.68073159697, 63953.36046639474, 64139.35860641334, 15456.38174473031, 15520.92239538795, 15589.298481568356, 15615.609609759847, 15660.92169539145, 11350.31898086101, 13214.207147571176, 13476.0, 13503.797443038362, 13565.59303220905, 18754.24983000692, 18786.624267514846, 18807.0, 18854.905725471286, 18897.244110235435, 6074.574779765374, 6145.262568491809, 6192.318844927055, 6258.812235632939, 6558.967205163944, 955.498363359228, 971.4317124482116, 984.7931934293879, 992.4888682328607, 1081.3133660125877, 32415.837920810245, 32480.83759581187, 32650.755401797614, 32789.344213115604, 33107.66892331094, 1035.6789395287485, 1084.3403513143141, 1134.551852018448, 1149.3219000789575, 1187.2342339191173, 16404.425845095364, 17579.912100439673, 17930.372436964644, 18186.907386025567, 18268.543286417946, 508152.0, 511407.0, 512276.0, 514388.0, 516348.0, 60728.69635651882, 60826.0, 61199.0, 61234.69382653058, 61249.0, 1265.8227848101274, 1394.539801865382, 1426.165693069566, 1476.1290838405391, 1544.4362807575153, 30495.695043050175, 34347.080498157935, 34355.312893742484, 34821.30357392787, 34978.65021349856, 53333.7333313331, 54349.45650543602, 54374.7281263607, 54380.456195439125, 54384.45615543794, 481580.0, 485912.0, 487113.0, 487783.0, 489832.0, 44188.55811441845, 44588.0, 45318.773406134074, 46231.0, 48730.26904596363, 49521.0, 49686.25470618016, 49987.25019124789, 50131.49868501268, 50770.74614626903, 42097.789511053474, 42292.36561451642, 42835.0, 42877.356839648055, 44279.11441771082, 41718.0, 41884.79057604692, 42805.57194428141, 43420.56579434165, 43856.56143438525, 41870.58129418667, 42104.3684344729, 43475.56524434715, 43514.0, 43615.12769744651, 34624.82687586546, 37612.93128877938, 37880.43179352367, 38412.80793596014, 38641.6135838638, 28541.714582854736, 28659.14022579326, 28679.569806452502, 28832.711672883004, 28834.567481488215], "value": 21238.864491650806, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.9791561464122, 395.5925396841251, 400.89376315276456, 401.45201799543634, 418.52470043098094, 15110.0, 19802.207911683516, 19807.702884456743, 19932.302369417073, 19958.90020549897, 19026.90486547565, 19115.426537203904, 19262.614747705036, 19388.0, 19457.41627751166, 5556.555475561966, 5567.387587365397, 5692.288463942007, 5739.110437882135, 5814.883702325972, 5649.0, 5726.198332233491, 5731.541476681856, 5731.770729170828, 5732.31212254528, 5701.401352857953, 5712.8857422851725, 7176.641167941582, 7493.325600695936, 7549.245075492462, 7262.600556969364, 7350.522216055966, 7454.291842274975, 7501.549907005598, 7520.586367749772, 7270.927290727078, 7287.271272872724, 7289.489735718502, 7289.708411663527, 7289.781306560759, 27157.864210679018, 27934.301642458897, 28041.0, 28272.575911361346, 28273.43453130926, 2444.9388765280833, 2445.1808644104244, 2482.4786794773117, 2531.1520640585427, 2592.8703564821685, 15481.0, 16345.83654163455, 16531.421400250932, 16735.83264167355, 17264.223109960083, 2152.9354119376444, 2277.7722227777253, 2361.4804742956694, 2424.502976889751, 2425.7331693513697, 61590.69204653948, 75470.24529754742, 84875.15124848734, 87660.56169719111, 102497.99999999926, 9448.433094014381, 9870.753231169134, 9901.950490247502, 9979.550920208609, 10083.546240419199, 13307.534236301877, 13407.0, 13436.731265374638, 13438.529651462151, 13448.193108413527, 7948.403869709795, 7955.124936257007, 8008.119106898236, 8032.1164671886045, 8100.959495202486, 7871.291583757375, 8078.555679437687, 8087.231712987257, 8254.669813207525, 8265.338772898154, 4041.1109555897656, 4136.958630413657, 4144.254034273859, 4221.176870510282, 4234.576542345802, 1940.0590713503939, 1979.881207127577, 1981.197614965941, 2008.6585280502056, 2048.8463365247667, 365.17834871539037, 367.368126821866, 372.5622393687461, 399.09405649176205, 1017.6489111256752, 11155.665330040112, 11360.0, 11431.428428578538, 11587.594434194763, 11662.94168529152, 396.6113209055142, 418.74875074955395, 423.53834320590704, 429.38812192626006, 435.3100335967464, 5979.225984384735, 6075.817725468243, 6079.422454866781, 6165.1985241919165, 6174.9073763893575, 514513.0, 516927.0, 522308.0, 528120.0, 536446.0, 98178.50910745544, 98272.0, 99511.0, 101397.0, 102724.0, 441.2763068567552, 447.583747115186, 449.3327408797932, 449.6245634894834, 449.8380582990121, 10457.790844182922, 10502.474876256309, 10608.257421980386, 10645.0, 10898.07366373867, 40873.182536349705, 42138.36792448177, 42700.78649606732, 43693.78153109214, 43753.78123109364, 60764.657943813516, 60844.6957765226, 60906.0, 62174.689126554076, 62377.37622623716, 8337.95831020841, 8360.414770966217, 8409.0, 8478.32173426112, 8790.560471976247, 10440.895591044296, 13200.933995329962, 13579.796303055264, 13595.252261125734, 13707.451701931812, 10430.426326552113, 10538.8419173714, 11606.187566870238, 11688.766224675288, 11854.703632409259, 10698.251122421356, 10960.725981850199, 11032.558697652325, 11357.261777984404, 11449.713757156138, 6126.632402055712, 9046.823912891283, 10595.205359598214, 11869.525218991144, 12208.328541929925, 8398.41211115216, 8479.915200847912, 8539.207294009884, 8609.48343099416, 8795.164459376349, 10994.450277486254, 11964.58123965657, 12323.50705971751, 12364.134510584172, 12796.872031279569], "value": 8558.092386329048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.0255209868649, 1465.3625672832318, 1525.4355888321322, 1649.5628658405524, 1788.2131861980736, 8174.550399728013, 8536.189062039111, 8565.35759818013, 8701.651933922636, 8727.5636218189, 8641.049484556694, 8739.781505462352, 8742.300615950724, 8750.21248087672, 8774.736757897273, 2345.698137533668, 2369.8815059246967, 2370.277065495023, 2372.6796882420826, 2372.79831214347, 2335.4745182333922, 2373.442241073355, 2376.025829409942, 2377.7265614454386, 2383.2373640435067, 2316.073570571776, 2324.395657129142, 2339.4268404240984, 2355.858648481088, 2359.563480756057, 2245.079517397859, 2266.761989991052, 2266.9433264168447, 2269.3645779181857, 2317.779810917977, 2282.2126366403563, 2287.7712228777154, 2288.9542209155725, 2290.484640955788, 2293.185918998765, 8719.912800872036, 8763.12368763125, 8788.0, 8869.55652217393, 8874.334424918157, 5202.297689811865, 5398.865028374283, 5399.055165346088, 5403.891922161535, 5409.026375252453, 3400.1669590950373, 3453.205762674599, 3453.689167974894, 3484.362349695639, 3486.7210623150086, 1156.8727439981594, 1161.4483120517687, 1168.6844551971005, 1202.9218100823507, 1207.8550573931097, 12344.197627154206, 12454.128211025232, 12507.874921250763, 12528.248305101723, 12535.498580056877, 4316.827326906889, 4542.409486766699, 4614.838480653161, 4639.652026098056, 4643.930341044887, 9806.509674516246, 9813.9018609813, 9824.361416507903, 9829.852552211723, 9830.361026533252, 5280.049591073567, 5280.339957505312, 5280.867978300574, 5290.074237008508, 5296.735163241822, 4359.585839345257, 4690.53094690535, 4800.495947925488, 4854.708717476963, 4956.330895329084, 3628.1111127773866, 3725.683316918038, 3777.773333599993, 3815.3323168445413, 3820.2359528094494, 1144.553624086607, 1149.0922171484433, 1149.4195431307137, 1167.235460773195, 1174.1546086817477, 779.8908152858604, 832.7501749475133, 898.2364989758653, 923.5290002098843, 971.7424882406168, 5681.829545113653, 5764.740586673526, 5781.132830075522, 5785.942140578541, 6104.633721976696, 442.2040327410712, 442.85828534868534, 454.69535411274524, 458.7385190441483, 460.7051487048292, 3924.274009308287, 3993.0815912340327, 4055.918881622351, 4094.713370064067, 4106.240345536085, 10963.83554246687, 11259.099272058216, 11320.547178112784, 11336.149788765875, 11344.149188810872, 11200.495977681025, 11210.27133236337, 11224.55101795919, 11252.887471125185, 11281.097512199005, 7436.442266830009, 8361.498310101413, 8366.0, 8434.325253979667, 8436.915630843612, 6055.031195008777, 6089.48239399656, 6177.6911154443, 6215.409536094064, 6320.178376810985, 10407.531661075422, 10408.47957602132, 10439.060484556427, 10458.320209186379, 10510.0, 10138.645147419806, 10420.426876521864, 10514.369137851754, 10620.256582039183, 10630.415327157081, 7093.1488221413765, 7834.4907581007055, 8017.959910200411, 8074.273315401642, 8259.628316725642, 8914.821703566022, 8985.326100542616, 8991.100889910957, 8992.055834137584, 8994.145556172152, 7250.3112204342615, 7266.7093316266755, 7468.402527797872, 7775.300222979959, 8080.555569443737, 7372.262773722585, 7429.628518573942, 7440.813979650552, 7492.662830172764, 7699.0, 6975.546589471668, 7042.964785176041, 7043.295670432915, 7059.047028651066, 7066.929330706627, 5110.514501122388, 5159.561437277724, 5210.270562121382, 5301.602379821452, 5454.918176227439, 5189.221616757516, 5249.291345668438, 5251.343582052244, 5364.2490051393615, 5569.888602227852], "value": 4546.14203873025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.68549136355017, 334.61352138280284, 342.0371653794567, 342.7583553594716, 343.00015454948823, 5065.974670126649, 5151.562117220036, 5191.169412893938, 5203.947960520393, 5262.158054711247, 5177.275181474596, 5180.45605211452, 5207.687538747668, 5209.531142197202, 5217.50433708797, 4138.896527586804, 4144.067584793427, 4149.979250103753, 4154.189932963073, 4167.395727619493, 3914.843406263746, 3940.4286378475103, 4086.8773936781945, 4107.363358679409, 4109.630133288004, 4077.4495443115097, 4083.5712250213746, 4089.7750623715683, 4090.3025244523487, 4093.69297302702, 4091.079507110891, 4100.466939297902, 4105.527864295599, 4120.196561670476, 4129.483814523184, 3427.7771944823508, 3497.2131270464197, 3587.7935069931805, 3592.191756854713, 3622.4023036198996, 5023.572996295283, 5037.773300201499, 5039.77321020555, 5047.722375269359, 5058.822941197077, 4624.745638989854, 4625.953740462586, 4636.930446043312, 4638.652101092398, 4644.373009643688, 5258.316418865561, 5348.438413966516, 5369.650972686802, 5389.8113566025, 5394.541463975567, 3980.2735126438483, 3995.62041606047, 4019.618136277049, 4022.1352409231863, 4023.0948036691802, 5660.547156227533, 5665.206871038058, 5674.687892165929, 5680.602357834953, 5694.288213973254, 3973.7218394712095, 4031.113155105872, 4038.7778672172726, 4070.1249231415386, 4080.8775736727944, 4108.568600296926, 4108.856190033334, 4116.086020000714, 4117.382392641128, 4123.9381409278885, 4685.625149987993, 4687.484376718558, 4699.78850951708, 4706.3646407735205, 4706.388169537938, 5225.921611175835, 5474.42518535556, 5485.6434331768305, 5517.393086760453, 5518.944810551923, 1401.70564181521, 1408.401429392504, 1418.7375335562954, 1419.2477986667084, 1420.3821337718125, 878.7846977490556, 912.0788004115758, 918.8392031394479, 926.346925417579, 929.5584597316356, 4636.84078980255, 4660.53394660538, 4701.882452938704, 4705.364775755298, 4740.383750112533, 5327.467253274642, 5425.945740542544, 5542.889142217133, 5618.578606604439, 5652.378238393773, 5309.2036194570355, 5319.148936170272, 5335.813246536352, 5337.2794672719465, 5347.625666203407, 2838.929026774347, 2957.275467510474, 2965.4958657028355, 2971.182924695726, 2976.5683975823817, 5791.957447659462, 5796.82609521715, 5803.245578074823, 5805.796797112081, 5809.767609295581, 5699.344575373802, 5708.744076303206, 5716.5140963017775, 5722.828315150552, 5724.198612194296, 5665.235193248858, 5732.971335143297, 5805.403514033675, 5813.906993482258, 5815.244018277682, 5504.174373843795, 5558.305211848519, 5603.859903502445, 5621.044422448275, 5629.043062679272, 5315.6611091119785, 5496.725163741877, 5507.586930980113, 5531.280933478622, 5531.474509921551, 5747.339056008613, 5760.567957403296, 5765.192872997867, 5786.132080188006, 5789.423706450589, 3994.7203695742182, 4031.8992025199605, 4059.0258337998075, 4069.348904175318, 4081.469408976814, 5471.534919531725, 5557.638753481011, 5560.721034162246, 5641.843422098542, 5645.350784659817, 5342.599305052059, 5387.0, 5389.326334208224, 5408.9459105410015, 5444.972775136099, 5375.354957405137, 5421.376541697755, 5440.945590544044, 5445.618806683653, 5458.608054945963, 5169.767360468863, 5248.186531087687, 5320.0, 5351.892962140813, 5383.246345511553, 5336.345732822837, 5350.34139416782, 5444.237806707143, 5449.863753406196, 5473.8357849264585, 4834.516548345137, 5116.769745361393, 5239.764210610456, 5244.954467757501, 5248.08158572256], "value": 4156.158187924333, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.7134617499177, 744.6909532543995, 746.3208480282943, 790.5928446849872, 825.7894236969576, 14972.475963341285, 15596.610084747887, 15799.526014219564, 15812.44656437025, 15832.445864394735, 13366.732665346688, 14355.282235888217, 14486.782698259534, 14514.419423223086, 14558.781618275709, 777.8716511775565, 797.1908512859452, 807.7415227127321, 808.6967387229789, 809.251442415766, 807.4549678966704, 809.9959500202491, 810.2990912860363, 810.9148539403366, 811.4238890387832, 853.1127627267634, 855.9058503564603, 856.3834039491556, 857.4255248983166, 857.4640849469082, 850.2730165708354, 850.8681154421074, 851.4465597361702, 853.8548446764065, 853.8847255620504, 617.1606615003592, 618.8112625649176, 619.5013014523307, 619.5260625621358, 619.6870580356891, 13789.0, 13915.373808178656, 14025.859741402557, 14431.278436078259, 14528.564143075724, 13457.192568445924, 13870.098443601133, 13892.583222503341, 13978.161310321413, 14334.856651433562, 11819.34993575353, 11884.286942783374, 11917.761644767055, 12603.873961260362, 12698.428570714248, 3877.651011408987, 3935.8032098394965, 3941.4876066111224, 4137.586241375869, 4143.81352839123, 82990.0, 86173.0, 87149.56425217833, 87956.0, 89700.55149724275, 9985.950070249603, 10091.192704583616, 10154.187664986783, 10199.235057370726, 10531.104856087319, 19573.804261957197, 19662.70505942412, 19671.311504097575, 19700.0, 19727.11227994715, 8679.392442528962, 8802.91197088021, 8893.822123557622, 8904.465732056098, 9137.954310228539, 4720.716756994591, 4940.135476291634, 4943.8022479101155, 4958.132326842788, 5043.5712964398435, 3173.841307934594, 3252.756043296762, 3260.918477038093, 3274.607047154309, 3287.6054873414864, 535.344203350892, 553.8809156031473, 559.8740283436194, 570.4466667332681, 577.742904407543, 612.2561088277764, 634.5780056262626, 693.7883945396602, 734.930181632744, 774.8915151878741, 10061.19510439163, 10061.748456288651, 10126.139278161292, 10395.32430392022, 10440.582376704999, 611.3825036712952, 654.3325807676239, 706.6996526476227, 763.7365109037373, 765.6554550452319, 4605.631549476034, 4759.785809638574, 4799.66402351832, 4981.526754958273, 5058.342415486037, 116914.0, 118005.0, 118245.40877295559, 119072.40463797799, 119171.0, 31765.364692706018, 37503.0, 94439.0, 100855.0, 102895.3697083633, 54682.45317546853, 72915.63542182255, 90980.0, 92813.0, 93917.5304123475, 9444.95277523608, 9689.079537443931, 9746.269029822934, 9756.365836220622, 9773.706788796348, 53698.31508424641, 53731.46268537421, 54276.0, 54336.45663543314, 55707.44292557023, 72308.0, 77087.0, 81170.0, 81959.0, 83174.58412707898, 12019.459124339252, 12053.0, 12055.51777928873, 12217.511299548274, 12772.680682983, 14125.34986005606, 14343.139411634918, 14368.353424096149, 14498.782518262446, 14510.0, 13734.0, 13801.723965520834, 13878.306084695927, 14045.719085618435, 14582.343794529059, 11818.704532386415, 12062.51749929993, 12132.454039568063, 12210.633680989584, 12791.232526048467, 13333.66665833362, 13385.732285354434, 13579.049466537643, 13771.586852394445, 14218.715625687222, 10975.341479510935, 11167.385793781095, 11232.775344493228, 11412.543498259976, 11428.885711142782, 10029.398236105857, 10130.89869101329, 10393.0, 11410.543578256777, 11423.200375973602], "value": 7434.361252255517, "unit": "score"}], "name": "dromaeo_css", "value": 7137.656637168934, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 111.0, 111.0, 111.0, 111.0, 110.0, 110.0, 111.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 118.0, 118.0, 184.0, 124.0, 124.0, 140.0, 119.0, 156.0, 118.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 160.0, 160.0, 161.0, 160.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 101.0, 75.0, 76.0, 74.0, 74.0, 108.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 121.0, 84.0, 84.0, 84.0, 120.0, 84.0, 83.0, 133.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 50.0, 51.0, 53.0, 52.0, 51.0, 50.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 61.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 77.0, 75.0, 76.0, 76.0, 81.0, 75.0, 75.0, 76.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 123.0, 121.0, 122.0, 125.0, 125.0, 119.0, 119.0, 138.0, 122.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 158.0, 157.0, 156.0, 156.0, 158.0, 157.0, 156.0, 155.0, 158.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 63.0, 62.0, 63.0, 62.0, 62.0, 61.0, 65.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1474.5, "alertThreshold": 2.0}]} 11:42:34 INFO - Return code: 0 11:42:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:42:34 INFO - # TBPL SUCCESS # 11:42:34 INFO - Running post-action listener: _resource_record_post_action 11:42:34 INFO - [mozharness: 2016-08-06 18:42:34.933839Z] Finished run-tests step (success) 11:42:34 INFO - Running post-run listener: _resource_record_post_run 11:42:35 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 554287104; Read time: 256; Write time: 645920 11:42:35 INFO - TinderboxPrint: CPU usage
13.0% 11:42:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 256 11:42:35 INFO - TinderboxPrint: I/O write bytes / time
554,287,104 / 645,920 11:42:35 INFO - TinderboxPrint: CPU idle
8,292.4 (87.0%) 11:42:35 INFO - TinderboxPrint: CPU user
1,189.8 (12.5%) 11:42:35 INFO - TinderboxPrint: Swap in / out
0 / 0 11:42:35 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 261591040; Read time: 0; Write time: 334580 11:42:35 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292696064; Read time: 256; Write time: 311340 11:42:35 INFO - Running post-run listener: _upload_blobber_files 11:42:35 INFO - Blob upload gear active. 11:42:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:42:35 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 11:42:35 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:42:35 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:42:35 INFO - (blobuploader) - INFO - Open directory for files ... 11:42:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:42:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:42:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:42:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:42:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:42:37 INFO - (blobuploader) - INFO - Done attempting. 11:42:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:42:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:42:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:42:38 INFO - (blobuploader) - INFO - Done attempting. 11:42:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:42:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:42:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:42:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:42:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:42:41 INFO - (blobuploader) - INFO - Done attempting. 11:42:41 INFO - (blobuploader) - INFO - Iteration through files over. 11:42:41 INFO - Return code: 0 11:42:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:42:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:42:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5be8c7e78ff5499a732c9aa4da9373954f6f6daae63af3bfe20f7f877c71ae0677dcc86f272ad03c6ef01d4c20798a95d313a056f8c207991795979b4c7805d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3552bb468fbb9cbf7a6804127c5d972c92a991c28309abda49291b987e4f14bfb2aefc018ad3a694c5741256962cdbb371c2a0923a26a0b0c165f0fef26114dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8246658fe327879a0ad95f737fd3d81e9d7caecf65a3c4c7d1163997e4cc4120cc1d46e9451eea47593844cb2d41c2027a3e69d4791499a19120697f42362b34"} 11:42:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:42:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:42:41 INFO - Contents: 11:42:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5be8c7e78ff5499a732c9aa4da9373954f6f6daae63af3bfe20f7f877c71ae0677dcc86f272ad03c6ef01d4c20798a95d313a056f8c207991795979b4c7805d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3552bb468fbb9cbf7a6804127c5d972c92a991c28309abda49291b987e4f14bfb2aefc018ad3a694c5741256962cdbb371c2a0923a26a0b0c165f0fef26114dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8246658fe327879a0ad95f737fd3d81e9d7caecf65a3c4c7d1163997e4cc4120cc1d46e9451eea47593844cb2d41c2027a3e69d4791499a19120697f42362b34"} 11:42:41 INFO - Running post-run listener: copy_logs_to_upload_dir 11:42:41 INFO - Copying logs to upload dir... 11:42:41 INFO - mkdir: /builds/slave/test/build/upload/logs 11:42:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.730984 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-06 11:42:41.381011) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 11:42:41.384806) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5be8c7e78ff5499a732c9aa4da9373954f6f6daae63af3bfe20f7f877c71ae0677dcc86f272ad03c6ef01d4c20798a95d313a056f8c207991795979b4c7805d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3552bb468fbb9cbf7a6804127c5d972c92a991c28309abda49291b987e4f14bfb2aefc018ad3a694c5741256962cdbb371c2a0923a26a0b0c165f0fef26114dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8246658fe327879a0ad95f737fd3d81e9d7caecf65a3c4c7d1163997e4cc4120cc1d46e9451eea47593844cb2d41c2027a3e69d4791499a19120697f42362b34"} build_url:https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/LBiinky8Tl22iTdmUWuvqA/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/Fx-Team/sha512/5be8c7e78ff5499a732c9aa4da9373954f6f6daae63af3bfe20f7f877c71ae0677dcc86f272ad03c6ef01d4c20798a95d313a056f8c207991795979b4c7805d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3552bb468fbb9cbf7a6804127c5d972c92a991c28309abda49291b987e4f14bfb2aefc018ad3a694c5741256962cdbb371c2a0923a26a0b0c165f0fef26114dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8246658fe327879a0ad95f737fd3d81e9d7caecf65a3c4c7d1163997e4cc4120cc1d46e9451eea47593844cb2d41c2027a3e69d4791499a19120697f42362b34"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 11:42:41.410928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:42:41.411333) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1470503238.723909-1244530386 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 11:42:41.496893) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 11:42:41.497258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 11:42:41.497614) ========= ========= Total master_lag: 0.18 =========