builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1472620440.3 results: success (0) buildid: 20160830193025 builduid: e8445d197c8e4fcf816eb8e4c2dc94db revision: 506facea63169a29e04eb140663da1730052db64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.300439) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.301000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.301392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.355124) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.355436) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 22:14:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2016-08-30 22:14:01 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.518705) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.519022) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023259 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.571105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:00.571894) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 506facea63169a29e04eb140663da1730052db64 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 506facea63169a29e04eb140663da1730052db64 --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=a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 22:14:01,294 truncating revision to first 12 chars 2016-08-30 22:14:01,294 Setting DEBUG logging. 2016-08-30 22:14:01,294 attempt 1/10 2016-08-30 22:14:01,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/506facea6316?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 22:14:01,597 unpacking tar archive at: mozilla-central-506facea6316/testing/mozharness/ program finished with exit code 0 elapsedTime=0.574955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:01.166962) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:01.167586) ========= script_repo_revision: 506facea63169a29e04eb140663da1730052db64 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:01.168370) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:01.168987) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 22:14:01.186421) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-30 22:14:01.187163) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:14:01 INFO - MultiFileLogger online at 20160830 22:14:01 in /builds/slave/test 22:14:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:14:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:14:01 INFO - {'append_to_log': False, 22:14:01 INFO - 'base_work_dir': '/builds/slave/test', 22:14:01 INFO - 'blob_upload_branch': 'Firefox', 22:14:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:14:01 INFO - 'branch': 'Firefox', 22:14:02 INFO - 'buildbot_json_path': 'buildprops.json', 22:14:02 INFO - 'config_files': ('talos/linux_config.py',), 22:14:02 INFO - 'default_actions': ('clobber', 22:14:02 INFO - 'read-buildbot-config', 22:14:02 INFO - 'download-and-extract', 22:14:02 INFO - 'populate-webroot', 22:14:02 INFO - 'create-virtualenv', 22:14:02 INFO - 'install', 22:14:02 INFO - 'run-tests'), 22:14:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:14:02 INFO - 'download_minidump_stackwalk': True, 22:14:02 INFO - 'download_symbols': 'ondemand', 22:14:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:14:02 INFO - 'tooltool.py': '/tools/tooltool.py', 22:14:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:14:02 INFO - '/tools/misc-python/virtualenv.py')}, 22:14:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:14:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:14:02 INFO - 'installer_path': 'installer.exe', 22:14:02 INFO - 'log_level': 'info', 22:14:02 INFO - 'log_name': 'talos', 22:14:02 INFO - 'log_to_console': True, 22:14:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:14:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:14:02 INFO - 'opt_config_files': (), 22:14:02 INFO - 'pip_index': False, 22:14:02 INFO - 'sps_profile': False, 22:14:02 INFO - 'sps_profile_interval': 0, 22:14:02 INFO - 'suite': 'dromaeojs', 22:14:02 INFO - 'system_bits': '32', 22:14:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:14:02 INFO - 'title': 'talos-linux64-ix-062', 22:14:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:14:02 INFO - 'use_talos_json': True, 22:14:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:14:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:14:02 INFO - 'work_dir': 'build'} 22:14:02 INFO - [mozharness: 2016-08-31 05:14:02.006788Z] Running clobber step. 22:14:02 INFO - Running pre-action listener: _resource_record_pre_action 22:14:02 INFO - Running main action method: clobber 22:14:02 INFO - rmtree: /builds/slave/test/build 22:14:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:14:02 INFO - Running post-action listener: _resource_record_post_action 22:14:02 INFO - [mozharness: 2016-08-31 05:14:02.483368Z] Finished clobber step (success) 22:14:02 INFO - [mozharness: 2016-08-31 05:14:02.483470Z] Running read-buildbot-config step. 22:14:02 INFO - Running pre-action listener: _resource_record_pre_action 22:14:02 INFO - Running main action method: read_buildbot_config 22:14:02 INFO - Using buildbot properties: 22:14:02 INFO - { 22:14:02 INFO - "project": "", 22:14:02 INFO - "product": "firefox", 22:14:02 INFO - "script_repo_revision": "production", 22:14:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:14:02 INFO - "repository": "", 22:14:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:14:02 INFO - "buildid": "20160830193025", 22:14:02 INFO - "pgo_build": "True", 22:14:02 INFO - "basedir": "/builds/slave/test", 22:14:02 INFO - "buildnumber": 846, 22:14:02 INFO - "slavename": "talos-linux64-ix-062", 22:14:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:14:02 INFO - "platform": "ubuntu64_hw", 22:14:02 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:14:02 INFO - "branch": "mozilla-central", 22:14:02 INFO - "repo_path": "mozilla-central", 22:14:02 INFO - "revision": "506facea63169a29e04eb140663da1730052db64", 22:14:02 INFO - "stage_platform": "linux64-pgo", 22:14:02 INFO - "builduid": "e8445d197c8e4fcf816eb8e4c2dc94db", 22:14:02 INFO - "slavebuilddir": "test" 22:14:02 INFO - } 22:14:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:14:02 INFO - Running post-action listener: _resource_record_post_action 22:14:02 INFO - [mozharness: 2016-08-31 05:14:02.485934Z] Finished read-buildbot-config step (success) 22:14:02 INFO - [mozharness: 2016-08-31 05:14:02.486026Z] Running download-and-extract step. 22:14:02 INFO - Running pre-action listener: _resource_record_pre_action 22:14:02 INFO - Running main action method: download_and_extract 22:14:02 INFO - mkdir: /builds/slave/test/build/tests 22:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:02 INFO - trying https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:14:02 INFO - Downloading https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:14:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:14:03 INFO - Downloaded 1361 bytes. 22:14:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:14:03 INFO - Using the following test package requirements: 22:14:03 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:14:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:14:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:14:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'jsshell-linux-x86_64.zip'], 22:14:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:14:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:14:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:14:03 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:14:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:14:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:14:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:14:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:03 INFO - trying https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:14:03 INFO - Downloading https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:14:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:14:03 INFO - Downloaded 21896201 bytes. 22:14:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 22:14:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:08 INFO - trying https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:14:08 INFO - Downloading https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:14:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:14:09 INFO - Downloaded 21896201 bytes. 22:14:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 22:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:13 INFO - trying https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:14:14 INFO - Downloaded 14413998 bytes. 22:14:14 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 22:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:14 INFO - trying https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:14:14 INFO - Downloading https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:14:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:14:15 INFO - Downloaded 59722263 bytes. 22:14:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:14:15 INFO - mkdir: /builds/slave/test/properties 22:14:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:14:15 INFO - Writing to file /builds/slave/test/properties/build_url 22:14:15 INFO - Contents: 22:14:15 INFO - build_url:https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:14:16 INFO - Running post-action listener: _resource_record_post_action 22:14:16 INFO - Running post-action listener: set_extra_try_arguments 22:14:16 INFO - [mozharness: 2016-08-31 05:14:16.477513Z] Finished download-and-extract step (success) 22:14:16 INFO - [mozharness: 2016-08-31 05:14:16.477744Z] Running populate-webroot step. 22:14:16 INFO - Running pre-action listener: _resource_record_pre_action 22:14:16 INFO - Running main action method: populate_webroot 22:14:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:14:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:14:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:14:16 INFO - '--tppagecycles', 22:14:16 INFO - '7'], 22:14:16 INFO - 'tests': ['tsvgm']}}, 22:14:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:14:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:14:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:14:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:14:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:14:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:14:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['damp', 'tps']}, 22:14:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'tests': ['damp', 'tps']}, 22:14:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['dromaeo_dom']}, 22:14:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:14:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['basic_compositor_video']}, 22:14:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:14:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['a11yr', 22:14:16 INFO - 'ts_paint', 22:14:16 INFO - 'tpaint', 22:14:16 INFO - 'sessionrestore', 22:14:16 INFO - 'sessionrestore_no_auto_restore', 22:14:16 INFO - 'tabpaint']}, 22:14:16 INFO - 'other-e10s': {'tests': ['a11yr', 22:14:16 INFO - 'ts_paint', 22:14:16 INFO - 'tpaint', 22:14:16 INFO - 'sessionrestore', 22:14:16 INFO - 'sessionrestore_no_auto_restore', 22:14:16 INFO - 'tabpaint']}, 22:14:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:14:16 INFO - 'ts_paint', 22:14:16 INFO - 'tpaint', 22:14:16 INFO - 'sessionrestore', 22:14:16 INFO - 'sessionrestore_no_auto_restore', 22:14:16 INFO - 'tabpaint']}, 22:14:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:14:16 INFO - 'ts_paint', 22:14:16 INFO - 'tpaint', 22:14:16 INFO - 'sessionrestore', 22:14:16 INFO - 'sessionrestore_no_auto_restore', 22:14:16 INFO - 'tabpaint']}, 22:14:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['a11yr', 22:14:16 INFO - 'ts_paint', 22:14:16 INFO - 'tpaint', 22:14:16 INFO - 'sessionrestore', 22:14:16 INFO - 'sessionrestore_no_auto_restore', 22:14:16 INFO - 'tabpaint']}, 22:14:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['a11yr', 22:14:16 INFO - 'ts_paint', 22:14:16 INFO - 'tpaint', 22:14:16 INFO - 'sessionrestore', 22:14:16 INFO - 'sessionrestore_no_auto_restore', 22:14:16 INFO - 'tabpaint']}, 22:14:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['tsvgx', 22:14:16 INFO - 'tsvgr_opacity', 22:14:16 INFO - 'tart', 22:14:16 INFO - 'tscrollx', 22:14:16 INFO - 'cart']}, 22:14:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:14:16 INFO - 'tsvgr_opacity', 22:14:16 INFO - 'tart', 22:14:16 INFO - 'tscrollx', 22:14:16 INFO - 'cart']}, 22:14:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'talos_options': ['--disable-e10s'], 22:14:16 INFO - 'tests': ['tp5o']}, 22:14:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'tests': ['tp5o']}, 22:14:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'talos_options': ['--disable-e10s', 22:14:16 INFO - '--xperf_path', 22:14:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:14:16 INFO - 'tests': ['tp5n']}, 22:14:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:14:16 INFO - 'talos_options': ['--xperf_path', 22:14:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:14:16 INFO - 'tests': ['tp5n']}}, 22:14:16 INFO - 'talos.zip': {'path': '', 22:14:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:14:16 INFO - Running post-action listener: _resource_record_post_action 22:14:16 INFO - [mozharness: 2016-08-31 05:14:16.496989Z] Finished populate-webroot step (success) 22:14:16 INFO - [mozharness: 2016-08-31 05:14:16.497080Z] Running create-virtualenv step. 22:14:16 INFO - Running pre-action listener: _resource_record_pre_action 22:14:16 INFO - Running main action method: create_virtualenv 22:14:16 INFO - Creating virtualenv /builds/slave/test/build/venv 22:14:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:14:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:14:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:14:16 INFO - Using real prefix '/usr' 22:14:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:14:17 INFO - Installing distribute.............................................................................................................................................................................................done. 22:14:19 INFO - Installing pip.................done. 22:14:19 INFO - Return code: 0 22:14:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:14:19 INFO - Using env: {'DISPLAY': ':0', 22:14:19 INFO - 'HOME': '/home/cltbld', 22:14:19 INFO - 'LANG': 'en_US.UTF-8', 22:14:19 INFO - 'LANGUAGE': 'en_US:en', 22:14:19 INFO - 'LOGNAME': 'cltbld', 22:14:19 INFO - 'MAIL': '/var/mail/cltbld', 22:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:19 INFO - 'MOZ_NO_REMOTE': '1', 22:14:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:19 INFO - 'NO_EM_RESTART': '1', 22:14:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:19 INFO - 'PWD': '/builds/slave/test', 22:14:19 INFO - 'SHELL': '/bin/bash', 22:14:19 INFO - 'SHLVL': '1', 22:14:19 INFO - 'TERM': 'linux', 22:14:19 INFO - 'TMOUT': '86400', 22:14:19 INFO - 'USER': 'cltbld', 22:14:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:19 INFO - '_': '/tools/buildbot/bin/python'} 22:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:14:19 INFO - Cleaning up... 22:14:19 INFO - Return code: 0 22:14:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:14:19 INFO - Using env: {'DISPLAY': ':0', 22:14:19 INFO - 'HOME': '/home/cltbld', 22:14:19 INFO - 'LANG': 'en_US.UTF-8', 22:14:19 INFO - 'LANGUAGE': 'en_US:en', 22:14:19 INFO - 'LOGNAME': 'cltbld', 22:14:19 INFO - 'MAIL': '/var/mail/cltbld', 22:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:19 INFO - 'MOZ_NO_REMOTE': '1', 22:14:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:19 INFO - 'NO_EM_RESTART': '1', 22:14:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:19 INFO - 'PWD': '/builds/slave/test', 22:14:19 INFO - 'SHELL': '/bin/bash', 22:14:19 INFO - 'SHLVL': '1', 22:14:19 INFO - 'TERM': 'linux', 22:14:19 INFO - 'TMOUT': '86400', 22:14:19 INFO - 'USER': 'cltbld', 22:14:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:19 INFO - '_': '/tools/buildbot/bin/python'} 22:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:19 INFO - Downloading/unpacking psutil>=3.1.1 22:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:14:21 INFO - Installing collected packages: psutil 22:14:21 INFO - Running setup.py install for psutil 22:14:21 INFO - building 'psutil._psutil_linux' extension 22:14:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:14:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:14:21 INFO - building 'psutil._psutil_posix' extension 22:14:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:14:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:14:21 INFO - Successfully installed psutil 22:14:22 INFO - Cleaning up... 22:14:22 INFO - Return code: 0 22:14:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:14: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')]} 22:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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')]} 22:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:14: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 mozsystemmonitor==0.3 22:14:22 INFO - Using env: {'DISPLAY': ':0', 22:14:22 INFO - 'HOME': '/home/cltbld', 22:14:22 INFO - 'LANG': 'en_US.UTF-8', 22:14:22 INFO - 'LANGUAGE': 'en_US:en', 22:14:22 INFO - 'LOGNAME': 'cltbld', 22:14:22 INFO - 'MAIL': '/var/mail/cltbld', 22:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:22 INFO - 'MOZ_NO_REMOTE': '1', 22:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:22 INFO - 'NO_EM_RESTART': '1', 22:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:22 INFO - 'PWD': '/builds/slave/test', 22:14:22 INFO - 'SHELL': '/bin/bash', 22:14:22 INFO - 'SHLVL': '1', 22:14:22 INFO - 'TERM': 'linux', 22:14:22 INFO - 'TMOUT': '86400', 22:14:22 INFO - 'USER': 'cltbld', 22:14:22 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:22 INFO - '_': '/tools/buildbot/bin/python'} 22:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:14:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:14:24 INFO - Installing collected packages: mozsystemmonitor 22:14:24 INFO - Running setup.py install for mozsystemmonitor 22:14:24 INFO - Successfully installed mozsystemmonitor 22:14:24 INFO - Cleaning up... 22:14:24 INFO - Return code: 0 22:14:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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', '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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:14: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 jsonschema==2.5.1 22:14:24 INFO - Using env: {'DISPLAY': ':0', 22:14:24 INFO - 'HOME': '/home/cltbld', 22:14:24 INFO - 'LANG': 'en_US.UTF-8', 22:14:24 INFO - 'LANGUAGE': 'en_US:en', 22:14:24 INFO - 'LOGNAME': 'cltbld', 22:14:24 INFO - 'MAIL': '/var/mail/cltbld', 22:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:24 INFO - 'MOZ_NO_REMOTE': '1', 22:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:24 INFO - 'NO_EM_RESTART': '1', 22:14:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:24 INFO - 'PWD': '/builds/slave/test', 22:14:24 INFO - 'SHELL': '/bin/bash', 22:14:24 INFO - 'SHLVL': '1', 22:14:24 INFO - 'TERM': 'linux', 22:14:24 INFO - 'TMOUT': '86400', 22:14:24 INFO - 'USER': 'cltbld', 22:14:24 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:24 INFO - '_': '/tools/buildbot/bin/python'} 22:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:24 INFO - Downloading/unpacking jsonschema==2.5.1 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:14:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:26 INFO - Downloading functools32-3.2.3-2.tar.gz 22:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:14:26 INFO - warning: no files found matching '*.txt' 22:14:26 INFO - no previously-included directories found matching 'build' 22:14:26 INFO - no previously-included directories found matching 'dist' 22:14:26 INFO - no previously-included directories found matching '.git*' 22:14:26 INFO - Installing collected packages: jsonschema, functools32 22:14:26 INFO - Running setup.py install for functools32 22:14:26 INFO - warning: no files found matching '*.txt' 22:14:26 INFO - no previously-included directories found matching 'build' 22:14:26 INFO - no previously-included directories found matching 'dist' 22:14:26 INFO - no previously-included directories found matching '.git*' 22:14:26 INFO - Successfully installed jsonschema functools32 22:14:26 INFO - Cleaning up... 22:14:26 INFO - Return code: 0 22:14:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:26 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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:14:26 INFO - Using env: {'DISPLAY': ':0', 22:14:26 INFO - 'HOME': '/home/cltbld', 22:14:26 INFO - 'LANG': 'en_US.UTF-8', 22:14:26 INFO - 'LANGUAGE': 'en_US:en', 22:14:26 INFO - 'LOGNAME': 'cltbld', 22:14:26 INFO - 'MAIL': '/var/mail/cltbld', 22:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:26 INFO - 'MOZ_NO_REMOTE': '1', 22:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:26 INFO - 'NO_EM_RESTART': '1', 22:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:26 INFO - 'PWD': '/builds/slave/test', 22:14:26 INFO - 'SHELL': '/bin/bash', 22:14:26 INFO - 'SHLVL': '1', 22:14:26 INFO - 'TERM': 'linux', 22:14:26 INFO - 'TMOUT': '86400', 22:14:26 INFO - 'USER': 'cltbld', 22:14:26 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:26 INFO - '_': '/tools/buildbot/bin/python'} 22:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:14:27 INFO - Cleaning up... 22:14:27 INFO - Return code: 0 22:14:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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', '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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:14: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 blobuploader==1.2.4 22:14:27 INFO - Using env: {'DISPLAY': ':0', 22:14:27 INFO - 'HOME': '/home/cltbld', 22:14:27 INFO - 'LANG': 'en_US.UTF-8', 22:14:27 INFO - 'LANGUAGE': 'en_US:en', 22:14:27 INFO - 'LOGNAME': 'cltbld', 22:14:27 INFO - 'MAIL': '/var/mail/cltbld', 22:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:27 INFO - 'MOZ_NO_REMOTE': '1', 22:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:27 INFO - 'NO_EM_RESTART': '1', 22:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:27 INFO - 'PWD': '/builds/slave/test', 22:14:27 INFO - 'SHELL': '/bin/bash', 22:14:27 INFO - 'SHLVL': '1', 22:14:27 INFO - 'TERM': 'linux', 22:14:27 INFO - 'TMOUT': '86400', 22:14:27 INFO - 'USER': 'cltbld', 22:14:27 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:27 INFO - '_': '/tools/buildbot/bin/python'} 22:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:27 INFO - Downloading/unpacking blobuploader==1.2.4 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14:29 INFO - Downloading blobuploader-1.2.4.tar.gz 22:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:14:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:14:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:29 INFO - Downloading docopt-0.6.1.tar.gz 22:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:14:30 INFO - Installing collected packages: blobuploader, requests, docopt 22:14:30 INFO - Running setup.py install for blobuploader 22:14:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:14:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:14:30 INFO - Running setup.py install for requests 22:14:30 INFO - Running setup.py install for docopt 22:14:30 INFO - Successfully installed blobuploader requests docopt 22:14:30 INFO - Cleaning up... 22:14:30 INFO - Return code: 0 22:14:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:30 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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:14:30 INFO - Using env: {'DISPLAY': ':0', 22:14:30 INFO - 'HOME': '/home/cltbld', 22:14:30 INFO - 'LANG': 'en_US.UTF-8', 22:14:30 INFO - 'LANGUAGE': 'en_US:en', 22:14:30 INFO - 'LOGNAME': 'cltbld', 22:14:30 INFO - 'MAIL': '/var/mail/cltbld', 22:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:30 INFO - 'MOZ_NO_REMOTE': '1', 22:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:30 INFO - 'NO_EM_RESTART': '1', 22:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:30 INFO - 'PWD': '/builds/slave/test', 22:14:30 INFO - 'SHELL': '/bin/bash', 22:14:30 INFO - 'SHLVL': '1', 22:14:30 INFO - 'TERM': 'linux', 22:14:30 INFO - 'TMOUT': '86400', 22:14:30 INFO - 'USER': 'cltbld', 22:14:30 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:30 INFO - '_': '/tools/buildbot/bin/python'} 22:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:14:30 INFO - Running setup.py (path:/tmp/pip-YB0O8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:14:30 INFO - Running setup.py (path:/tmp/pip-fkSx6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:14:30 INFO - Running setup.py (path:/tmp/pip-tWnChq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:14:31 INFO - Running setup.py (path:/tmp/pip-MhPd54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:14:31 INFO - Running setup.py (path:/tmp/pip-zhlZpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:14:31 INFO - Running setup.py (path:/tmp/pip-FRa_ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:14:31 INFO - Running setup.py (path:/tmp/pip-IOl0JW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:14:31 INFO - Running setup.py (path:/tmp/pip-ZM8JMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:14:31 INFO - Running setup.py (path:/tmp/pip-MV5gfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:14:31 INFO - Running setup.py (path:/tmp/pip-644yeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:14:31 INFO - Running setup.py (path:/tmp/pip-b9qwl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:14:31 INFO - Running setup.py (path:/tmp/pip-vzwdyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:14:31 INFO - Running setup.py (path:/tmp/pip-xgaYhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:14:31 INFO - Running setup.py (path:/tmp/pip-66CH0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:31 INFO - Running setup.py (path:/tmp/pip-X7ryOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:14:31 INFO - Running setup.py (path:/tmp/pip-izD9Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:14:31 INFO - Running setup.py (path:/tmp/pip-KFa9fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:14:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:14:32 INFO - Running setup.py install for manifestparser 22:14:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Running setup.py install for mozcrash 22:14:32 INFO - Running setup.py install for mozdebug 22:14:32 INFO - Running setup.py install for mozdevice 22:14:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Running setup.py install for mozfile 22:14:32 INFO - Running setup.py install for mozhttpd 22:14:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Running setup.py install for mozinfo 22:14:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Running setup.py install for mozInstall 22:14:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:14:32 INFO - Running setup.py install for mozleak 22:14:32 INFO - Running setup.py install for mozlog 22:14:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Running setup.py install for moznetwork 22:14:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Running setup.py install for mozprocess 22:14:33 INFO - Running setup.py install for mozprofile 22:14:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Running setup.py install for mozrunner 22:14:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Running setup.py install for mozscreenshot 22:14:33 INFO - Running setup.py install for moztest 22:14:33 INFO - Running setup.py install for mozversion 22:14:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:14:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:14:33 INFO - Cleaning up... 22:14:33 INFO - Return code: 0 22:14:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14: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')]} 22:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14: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')]} 22:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:33 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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:14:33 INFO - Using env: {'DISPLAY': ':0', 22:14:33 INFO - 'HOME': '/home/cltbld', 22:14:33 INFO - 'LANG': 'en_US.UTF-8', 22:14:33 INFO - 'LANGUAGE': 'en_US:en', 22:14:33 INFO - 'LOGNAME': 'cltbld', 22:14:33 INFO - 'MAIL': '/var/mail/cltbld', 22:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:33 INFO - 'MOZ_NO_REMOTE': '1', 22:14:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:33 INFO - 'NO_EM_RESTART': '1', 22:14:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:33 INFO - 'PWD': '/builds/slave/test', 22:14:33 INFO - 'SHELL': '/bin/bash', 22:14:33 INFO - 'SHLVL': '1', 22:14:33 INFO - 'TERM': 'linux', 22:14:33 INFO - 'TMOUT': '86400', 22:14:33 INFO - 'USER': 'cltbld', 22:14:33 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:33 INFO - '_': '/tools/buildbot/bin/python'} 22:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:14:34 INFO - Running setup.py (path:/tmp/pip-p5Ufvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:14:34 INFO - Running setup.py (path:/tmp/pip-oDZ4ZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:14:34 INFO - Running setup.py (path:/tmp/pip-eF3SZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:14:34 INFO - Running setup.py (path:/tmp/pip-q1ursi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:14:34 INFO - Running setup.py (path:/tmp/pip-D9ErZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:14:34 INFO - Running setup.py (path:/tmp/pip-qXNAVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:14:34 INFO - Running setup.py (path:/tmp/pip-WRhfDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:14:34 INFO - Running setup.py (path:/tmp/pip-d1FW0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:14:34 INFO - Running setup.py (path:/tmp/pip-KvR_CD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:14:34 INFO - Running setup.py (path:/tmp/pip-udCxxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:14:34 INFO - Running setup.py (path:/tmp/pip-r47ETI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:14:34 INFO - Running setup.py (path:/tmp/pip-tktoST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:14:34 INFO - Running setup.py (path:/tmp/pip-yA73Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:14:35 INFO - Running setup.py (path:/tmp/pip-kJI3xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:35 INFO - Running setup.py (path:/tmp/pip-pEQaO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:14:35 INFO - Running setup.py (path:/tmp/pip-LMMSPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:14:35 INFO - Running setup.py (path:/tmp/pip-rLXp9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:14:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:14:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:38 INFO - Downloading blessings-1.6.tar.gz 22:14:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:14:38 INFO - Installing collected packages: blessings 22:14:38 INFO - Running setup.py install for blessings 22:14:38 INFO - Successfully installed blessings 22:14:38 INFO - Cleaning up... 22:14:38 INFO - Return code: 0 22:14:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:14:38 INFO - Reading from file tmpfile_stdout 22:14:38 INFO - Current package versions: 22:14:38 INFO - argparse == 1.2.1 22:14:38 INFO - blessings == 1.6 22:14:38 INFO - blobuploader == 1.2.4 22:14:38 INFO - docopt == 0.6.1 22:14:38 INFO - functools32 == 3.2.3-2 22:14:38 INFO - jsonschema == 2.5.1 22:14:38 INFO - manifestparser == 1.1 22:14:38 INFO - mozInstall == 1.12 22:14:38 INFO - mozcrash == 0.17 22:14:38 INFO - mozdebug == 0.1 22:14:38 INFO - mozdevice == 0.48 22:14:38 INFO - mozfile == 1.2 22:14:38 INFO - mozhttpd == 0.7 22:14:38 INFO - mozinfo == 0.9 22:14:38 INFO - mozleak == 0.1 22:14:38 INFO - mozlog == 3.3 22:14:38 INFO - moznetwork == 0.27 22:14:38 INFO - mozprocess == 0.23 22:14:38 INFO - mozprofile == 0.28 22:14:38 INFO - mozrunner == 6.12 22:14:38 INFO - mozscreenshot == 0.1 22:14:38 INFO - mozsystemmonitor == 0.3 22:14:38 INFO - moztest == 0.7 22:14:38 INFO - mozversion == 1.4 22:14:38 INFO - psutil == 3.1.1 22:14:38 INFO - requests == 1.2.3 22:14:38 INFO - wsgiref == 0.1.2 22:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:38 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 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:14:38 INFO - Using env: {'DISPLAY': ':0', 22:14:38 INFO - 'HOME': '/home/cltbld', 22:14:38 INFO - 'LANG': 'en_US.UTF-8', 22:14:38 INFO - 'LANGUAGE': 'en_US:en', 22:14:38 INFO - 'LOGNAME': 'cltbld', 22:14:38 INFO - 'MAIL': '/var/mail/cltbld', 22:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:38 INFO - 'MOZ_NO_REMOTE': '1', 22:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:38 INFO - 'NO_EM_RESTART': '1', 22:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:38 INFO - 'PWD': '/builds/slave/test', 22:14:38 INFO - 'SHELL': '/bin/bash', 22:14:38 INFO - 'SHLVL': '1', 22:14:38 INFO - 'TERM': 'linux', 22:14:38 INFO - 'TMOUT': '86400', 22:14:38 INFO - 'USER': 'cltbld', 22:14:38 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:38 INFO - '_': '/tools/buildbot/bin/python'} 22:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:14:38 INFO - Cleaning up... 22:14:38 INFO - Return code: 0 22:14:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f05c4a20f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d2f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:14:38 INFO - Using env: {'DISPLAY': ':0', 22:14:38 INFO - 'HOME': '/home/cltbld', 22:14:38 INFO - 'LANG': 'en_US.UTF-8', 22:14:38 INFO - 'LANGUAGE': 'en_US:en', 22:14:38 INFO - 'LOGNAME': 'cltbld', 22:14:38 INFO - 'MAIL': '/var/mail/cltbld', 22:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:38 INFO - 'MOZ_NO_REMOTE': '1', 22:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:38 INFO - 'NO_EM_RESTART': '1', 22:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:38 INFO - 'PWD': '/builds/slave/test', 22:14:38 INFO - 'SHELL': '/bin/bash', 22:14:38 INFO - 'SHLVL': '1', 22:14:38 INFO - 'TERM': 'linux', 22:14:38 INFO - 'TMOUT': '86400', 22:14:38 INFO - 'USER': 'cltbld', 22:14:38 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:38 INFO - '_': '/tools/buildbot/bin/python'} 22:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:14:39 INFO - Cleaning up... 22:14:39 INFO - Return code: 0 22:14:39 INFO - Running post-action listener: _resource_record_post_action 22:14:39 INFO - Running post-action listener: _start_resource_monitoring 22:14:39 INFO - Starting resource monitoring. 22:14:39 INFO - [mozharness: 2016-08-31 05:14:39.157398Z] Finished create-virtualenv step (success) 22:14:39 INFO - [mozharness: 2016-08-31 05:14:39.157862Z] Running install step. 22:14:39 INFO - Running pre-action listener: _resource_record_pre_action 22:14:39 INFO - Running main action method: install 22:14:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:14:39 INFO - Reading from file tmpfile_stdout 22:14:39 INFO - Detecting whether we're running mozinstall >=1.0... 22:14:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:14:39 INFO - Reading from file tmpfile_stdout 22:14:39 INFO - Output received: 22:14:39 INFO - Usage: mozinstall [options] installer 22:14:39 INFO - Options: 22:14:39 INFO - -h, --help show this help message and exit 22:14:39 INFO - -d DEST, --destination=DEST 22:14:39 INFO - Directory to install application into. [default: 22:14:39 INFO - "/builds/slave/test"] 22:14:39 INFO - --app=APP Application being installed. [default: firefox] 22:14:39 INFO - mkdir: /builds/slave/test/build/application 22:14:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:14:56 INFO - Reading from file tmpfile_stdout 22:14:56 INFO - Output received: 22:14:56 INFO - /builds/slave/test/build/application/firefox/firefox 22:14:56 INFO - Running post-action listener: _resource_record_post_action 22:14:56 INFO - [mozharness: 2016-08-31 05:14:56.473248Z] Finished install step (success) 22:14:56 INFO - [mozharness: 2016-08-31 05:14:56.473531Z] Running run-tests step. 22:14:56 INFO - Running pre-action listener: _resource_record_pre_action 22:14:56 INFO - Running main action method: run_tests 22:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:14:56 INFO - Python 2.7.3 22:14:56 INFO - Return code: 0 22:14:56 INFO - grabbing minidump binary from tooltool 22:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:14:56 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 0x1d2a370>, '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 0x1d2d8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d2dd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:14:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:14:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:14:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:14:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:14:56 INFO - Return code: 0 22:14:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:14:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:14:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:14:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:14:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:14:56 INFO - Using env: {'DISPLAY': ':0', 22:14:56 INFO - 'HOME': '/home/cltbld', 22:14:56 INFO - 'LANG': 'en_US.UTF-8', 22:14:56 INFO - 'LANGUAGE': 'en_US:en', 22:14:56 INFO - 'LOGNAME': 'cltbld', 22:14:56 INFO - 'MAIL': '/var/mail/cltbld', 22:14:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:14:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:56 INFO - 'MOZ_NO_REMOTE': '1', 22:14:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:56 INFO - 'NO_EM_RESTART': '1', 22:14:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:56 INFO - 'PWD': '/builds/slave/test', 22:14:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:14:56 INFO - 'SHELL': '/bin/bash', 22:14:56 INFO - 'SHLVL': '1', 22:14:56 INFO - 'TERM': 'linux', 22:14:56 INFO - 'TMOUT': '86400', 22:14:56 INFO - 'USER': 'cltbld', 22:14:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851', 22:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:56 INFO - '_': '/tools/buildbot/bin/python'} 22:14:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:14:56 INFO - mozversion application_buildid: 20160830193025 22:14:56 INFO - mozversion application_changeset: 506facea63169a29e04eb140663da1730052db64 22:14:56 INFO - mozversion application_display_name: Nightly 22:14:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:14:56 INFO - mozversion application_name: Firefox 22:14:56 INFO - mozversion application_remotingname: firefox 22:14:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:14:56 INFO - mozversion application_vendor: Mozilla 22:14:56 INFO - mozversion application_version: 51.0a1 22:14:56 INFO - mozversion platform_buildid: 20160830193025 22:14:56 INFO - mozversion platform_changeset: 506facea63169a29e04eb140663da1730052db64 22:14:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:14:56 INFO - mozversion platform_version: 51.0a1 22:14:56 INFO - using testdate: 1472620496 22:14:56 INFO - actual date: 1472620496 22:14:56 INFO - starting webserver on 'localhost:43320' 22:14:56 INFO - SUITE-START | Running 2 tests 22:14:56 INFO - TEST-START | dromaeo_css 22:14:56 INFO - operating with platform_type : linux_ 22:14:56 INFO - Initialising browser for dromaeo_css test... 22:14:56 INFO - TEST-INFO | started process 11373 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZTpVMv/profile http://localhost:43320/getInfo.html) 22:15:00 INFO - PROCESS | 11373 | __metrics Screen width/height:1600/1200 22:15:00 INFO - PROCESS | 11373 | colorDepth:24 22:15:00 INFO - PROCESS | 11373 | Browser inner width/height: 1024/697 22:15:00 INFO - PROCESS | 11373 | __metrics 22:15:01 INFO - PROCESS | 11373 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:15:01 INFO - PROCESS | 11373 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:15:01 INFO - PROCESS | 11373 | console.error: PushService: 22:15:01 INFO - PROCESS | 11373 | stateChangeProcessEnqueue: Error transitioning state 22:15:01 INFO - PROCESS | 11373 | UnknownError 22:15:02 INFO - TEST-INFO | 11373: exit 0 22:15:02 INFO - Browser initialized. 22:15:02 INFO - Running cycle 1/1 for dromaeo_css test... 22:15:02 INFO - TEST-INFO | started process 11455 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZTpVMv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:14 INFO - PROCESS | 11455 | RSS: Main: 180690944 22:15:14 INFO - PROCESS | 11455 | 22:16:06 INFO - PROCESS | 11455 | 1472620566143 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:18:15 INFO - PROCESS | 11455 | Cycle 1(1): loaded http://localhost:43320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43320/tests/dromaeo/cssquery-ext.html) 22:18:16 INFO - PROCESS | 11455 | RSS: Main: 282988544 22:18:16 INFO - PROCESS | 11455 | 22:21:18 INFO - PROCESS | 11455 | Cycle 1(1): loaded http://localhost:43320/tests/dromaeo/cssquery-ext.html (next: http://localhost:43320/tests/dromaeo/cssquery-jquery.html) 22:21:19 INFO - PROCESS | 11455 | RSS: Main: 268210176 22:21:19 INFO - PROCESS | 11455 | 22:24:21 INFO - PROCESS | 11455 | Cycle 1(1): loaded http://localhost:43320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43320/tests/dromaeo/cssquery-mootools.html) 22:24:22 INFO - PROCESS | 11455 | RSS: Main: 258285568 22:24:22 INFO - PROCESS | 11455 | 22:27:24 INFO - PROCESS | 11455 | Cycle 1(1): loaded http://localhost:43320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43320/tests/dromaeo/cssquery-prototype.html) 22:27:24 INFO - PROCESS | 11455 | RSS: Main: 215703552 22:27:24 INFO - PROCESS | 11455 | 22:30:27 INFO - PROCESS | 11455 | Cycle 1(1): loaded http://localhost:43320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43320/tests/dromaeo/cssquery-yui.html) 22:30:28 INFO - PROCESS | 11455 | RSS: Main: 215760896 22:30:28 INFO - PROCESS | 11455 | 22:33:30 INFO - PROCESS | 11455 | Cycle 1(1): loaded http://localhost:43320/tests/dromaeo/cssquery-yui.html (next: http://localhost:43320/tests/dromaeo/cssquery-dojo.html) 22:33:31 INFO - PROCESS | 11455 | RSS: Main: 312037376 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | __start_tp_report 22:33:31 INFO - PROCESS | 11455 | _x_x_mozilla_page_load 22:33:31 INFO - PROCESS | 11455 | _x_x_mozilla_page_load_details 22:33:31 INFO - PROCESS | 11455 | |i|pagename|runs| 22:33:31 INFO - PROCESS | 11455 | |0;dojo.html;2945.116465060482;3003.8798448062066;3008.8796448142075;3031.3028003559193;3061.3724186541763;5243.10867152584;5244.344456942882;5301.787928482856;5359.89280214396;5371.543418809401;5300.496452836984;5303.390110137336;5374.838754837351;5382.085045542258;5428.995635807379;4766.475687674361;4819.036192761444;4820.951790482085;4826.638002149835;5052.191649336107;4914.287428322891;4939.753012349386;4964.826231081913;4984.75076246188;4987.251912213161;4759.5002524734755;4806.975965120169;4814.398200224972;4847.103285892122;4888.364512613355;4808.831690890802;4818.7831547580445;4893.0703166398625;4895.265710143472;4906.215005599122;4816.060868130716;4845.878853028667;4852.053849499349;4880.975595122037;4895.339129217546;5138.640295179339;5376.193570964348;5385.865353366158;5387.4612538746205;5396.91904621431;5631;5646.717664116819;5712.742926568314;5727.799527016535;5733.226014488033;5722.256106706143;5751.8562035949435;5759.164921086461;5775.364709881909;5782.132680097977;5239.947600524022;5312.203169524602;5394.649347792381;5394.78420863169;5406.54044406226;5837.88324233513;5856.6486010838635;5859.912101318484;5928.703564821742;5934.703264836741;5628.183913332544;5630.40880707528;5632.605717599728;5634.32388113429;5637.577181711388;5526.613137080446;5683.460071293303;5692.516136128475;5692.829215123553;5709.628874123168;5700.8004719835435;5786.929418057673;5787.074068149076;5792.102224155263;5795.623284486495;5688.288963879515;5733.512651424676;5736.4550367715;5739.684317362502;5796.971015144897;4405.955940440619;4452.131834292347;4453.198424283595;4455.131249406335;4504.07666428388;3918.74528155669;3943.0930885896405;3968.7023473239624;3975.5030621172355;3996.440498330236;5459.754311055933;5480.232767412565;5497.780088796483;5498.725063746797;5551.916721249185;5783.161441590946;5788.392218816964;5793.826185214451;5797.971010144923;5798.6810725410505;5289.312389389354;5316.149416093406;5362.088444964365;5365.195220716923;5453.4273901240595;4918.926216106761;4921.753912304371;4923.458419573844;4924.704517728947;4972.5027497249985;5828.271466066826;5869.178315035899;5869.589128761031;5882.794102206402;5927.051671732502;5615.859603509945;5669.744861481326;5681.034224181982;5686.175504551734;5697.886042279214;5809.477147056785;5825.563082768894;5833.387494313036;5890.911636325544;5905.911411328748;5488.121900496061;5532.30846144232;5551.139573366135;5552.083906155439;5567.387587365468;5622.409646987007;5668.3198016236665;5679.233303504139;5718.742656580381;5764.481196692318;5653.321601407857;5666.405027472221;5670.574706897081;5705.115707065412;5746.339170995389;5586.385497595343;5646.35066967304;5649.180868774087;5650.237217975685;5654.038813401649;5434.673919564839;5449.972750136224;5485.945140548543;5572.777088916398;5580.720963951705;5565.499105080562;5632.5775566831835;5635.577331700057;5684.7157642118555;5698.31620205578;5425.538829199562;5430.456954304538;5438.510534051796;5440.483154100354;5443.537299329601 22:33:31 INFO - PROCESS | 11455 | |1;ext.html;548.923150758894;566.5694072504896;596.1802521532892;598.6049207523035;600.7927265093541;32176;35813.46279805802;35879;42644;43952.78023609878;44987.325190122174;45273.09453810922;45559.77220113903;45617.54382456175;45675.314870276976;8942.150495702914;9071.818563628723;9093.818123637557;9095.818083638324;9097.317701172438;8230.2592766651;8410.915890841105;8882.600282987281;10591.894081059207;10840;11046.392448415334;11069.50187241576;11124;11129.443527823616;11140.888591114066;10876.945615271912;10899.400532970683;10903.454827258605;10919.12646988239;10924.890751092546;8083.151269116775;8293.502389856629;8307.501549907025;10599.0990765785;10791.406472644001;41605.375919361235;42463.78768106171;42538;46232;46253.30620040668;3463.515107884898;3774.471573979645;4148.56440073794;4187.706860519765;4196.307609244471;31746.365072698416;32658;32762.508562371582;32763;33003.339933201445;4340.891477713051;4410.095930334273;4548.20406428877;4548.863534093983;4642.0715856828765;346503;348251;348502;348503.2574837135;349128;57009.42990570041;57328.85342293131;57562;57849.421505784405;58358.416415836145;60762.39237607567;60884.95575221221;61057;61062.389376106556;61170.38829611647;15920.601984950468;16241.59396015109;16268.186590670419;16269.10519921392;16272.349106035863;11577.189596728316;11603.303801771921;11618.419079046014;11622.476988535536;11624.88375116255;14621.926890365692;16337.346506139858;16570.58573535647;16779.161041947853;16837.831621683872;5711.857203569861;5960.552958528127;6095.116208149793;6141.232345956755;6238.469730072995;910.913463220993;975.6341371985505;1008.6570566007592;1027.7430642339416;1030.469308306223;31565.36869262602;31618.525722114187;31624.256361545493;31919;32467;1060.5863713151878;1087.8912108789057;1110.8167152419921;1157.473349625935;1186.7982442984714;15539.45611903578;16512.42206522766;16742;16789.83210167883;17062.829371706124;551594;552811;555373;558093;559771;56412.43587564072;56528.43471565314;56622.71688641531;56801.431985679614;56971.71514142403;1309.214471317202;1318.2815365625756;1329.8271224740722;1343.932803359828;1350.0684527675871;29666.703332967256;30800.53799192969;31857.362852743277;31910.84044579762;32108.518372223967;50464.74767626138;51019.744901275255;51021.234681479065;51124.74437627788;51129.23306150485;458000;460691;460776;461222;461322;36615;38230.3113896338;39175.80412097921;41639.58360416357;42029.36955945602;46502.76748616235;46791.29813052875;46929;47047;47290;39227.215455691294;39983;40716.59283407128;40960.59039409568;42518;40126;40143;40909.590904090575;41009.794951025055;41045;39381.212375751755;39948.80274114539;40061;40077.398839018024;40219.798901005306;33875.83062084674;35013.824930875184;36756;37116.81441592883;37162.62837371592;26978.460430791667;27900.30249243704;27932.86033569819;29187;29544.556831647973 22:33:31 INFO - PROCESS | 11455 | |2;jquery.html;382.8659969010845;397.5726094448467;412.0933945320295;416.55636746864593;418.493622716513;15438.150901700403;18833.811661883377;18860.905695471538;19094.90452547737;19839.801601983974;18971.71542426865;19044.238230470764;19506;19609.411717648458;19769;5256.947430525704;5507.284053073095;5709.343425506057;5714.228579141804;5757.913631295534;5575.526080283181;5666.773329066832;5699.287589051369;5739.282589676291;5741.224934633834;5391.514763671269;5662.065759149736;5690.146478028309;5692.8861422771515;5695;5650.971745141269;5666.291713535808;5709.429057094258;5715.057015592443;5718.056520674084;4937.703737775745;5095.210242412395;5123.641345105844;5130.53825155734;5136.589072874199;23506.64740028901;23608;23692.289231323088;23705.288841334786;23922;2442.255112190781;2559.8080143989278;2646.470705858836;3000.339925216449;3066.9079927602206;13852.376643051088;14732.55802325932;14735.336909839083;14926;15382.538523844303;1948.7076938459202;1990.263602467082;1999.6400647883374;2024.210557882427;2044.7852975437663;65758;67881.32118678799;70359;71627;78162;10242.948785256174;10723.463826808784;11021.944890275498;11055.889441105646;11056.723581910355;15944.202789860692;15957.840421595636;15959.920200399156;15991.840081599268;15993.04041757475;7699.923000770033;7954;7976;8070.354371650254;8097.595120243965;7308.159561650478;7314.890276645855;7423.48035637511;7634.656440460192;7638.847223055508;4649;4765.547273009059;4826.359037927122;4913.459519452857;4963.751812409365;1968.6161198566429;2013.9295124670566;2024.170090262985;2058.8455865810124;2066.5763518478675;847.5846835050904;928.3640706116332;959.5586030426082;969.9806003879883;989.871316728835;17551.297948081934;18275.177617007266;18847.340343087926;19044.238230470626;19293.710594341097;955.8709574207393;956.6316967967383;979.6522234606755;1003.6988903328972;1021.5584752441671;10543.26197166191;10813.755873237997;10899.455027248765;10909.290896091728;10929.071028962468;407724.76825695275;427248;479072;488876;490032;94155.52922235482;94396.52801735948;95609.96095409882;96511.51744241234;96769.51615241879;982.3123813330698;1023.1456733627356;1044.8589440425446;1047.4762618690654;1053.2521909444226;16550.33798648089;16841.494755157364;17310.5672358187;17879.821201787814;17944.19251133728;42877.57122428861;42989.57010429856;44961.550384495735;45137.548624513336;45423.54576454193;55473.72263138658;55576.44423555713;57065.42934570601;58645;62758;8401.369897257608;8518.744437666632;8554.101819309131;8732.388732788644;8821.338399619926;9464;13290.136141150795;13530.255835929118;13820.93089534586;14018.088824226392;11519.481623326803;11664.825027624762;11788;11935.82096268574;12443.253404795742;11170.944145279222;11543.480543375736;11700.765984680429;11789.882101178879;11822.231554948901;11430.657080287605;11542.653720388402;11972;12033.398330083635;12066.457009434422;8286.875696864672;8522.531260780717;8641.481511109354;8836.867446988172;8928.687495937611;10853.837192441963;10946.412770148443;11102.111831053659;11358.488868000795;11359.09127269833 22:33:31 INFO - PROCESS | 11455 | |3;mootools.html;1344.8587898270687;1469.103846653541;1501.7221813964418;1630.2989714422802;1789.7852257729076;8623.267022303104;8987.59555819988;9065.818683626332;9301.90698093019;9320.533973301342;9048.366614337;9161.633534658622;9165.495897725623;9169.862452063207;9170.679026234067;2173.619616567102;2425.9514809703796;2506.8370555913853;2527.36815796051;2536.112360673768;2526.0906073813503;2558.6289987951736;2559.398541342783;2561.70540387855;2563.47448773002;2429.2105065853666;2451.9754802451926;2468.790152837011;2482.453860150764;2489.166129346671;2326.639370897514;2419.8064154867657;2428.3929017745563;2442.05980697431;2447.840890342131;2430.5989511730545;2458.9016439342404;2474.6040633498733;2477.752224777526;2480.0327872129706;9038;9235.399699019543;9236.72289831306;9240.260779137652;9243.168114869626;5463.754131064112;5592.636478628876;5600.691961942091;5600.971995140039;5609.747561359749;3423.5720534933134;3529.3294274087843;3659.2681463707363;3710.7402481826284;3725.9441108383394;1207.3721664734376;1213.3751118174148;1217.9269243845308;1250.593557093948;1254.9811752823714;12886.226826390446;13022.153560018663;13025.413856376486;13029.087963842534;13047.86952130476;4707.529247075265;4772.164871147535;4783.932981213059;4909.189983652729;4955.702657840541;10088.848667269996;10107.646232381978;10111.494425278706;10118.342307749972;10131.037551432602;5571.415001424792;5573.27547418833;5573.526250268773;5577.776888924398;5581;4582.633389328846;4612.76936153191;5057.620678449131;5178.482151784867;5210.062188806051;3975.6421922027157;3977.622125898035;4117.773522456294;4128.938065929014;4131.8967025824595;1219.5670536959428;1249.9125061245807;1256.830327905721;1262.3120399382358;1274.8852603265566;815.2621877201143;930.1721467893581;946.654471118037;952.2524818017961;964.4599024546277;6015.428534289235;6195.605988652517;6199.070139479114;6216.502679785697;6296.433321001132;458.6995517935757;464.12744041202325;469.5797261450982;475.4152392557105;475.4935993167266;4093.9385909211387;4095.9180816383505;4109.4246805447265;4119.835206591702;4124.835006599702;11327.716807079889;11510;11624.709382265512;11680.824787628193;11687.88312116885;11522.36626985507;11542.538298468135;11545.769084618261;11550.364729939934;11569.305841649528;8112.553809540532;8464.111268316861;8467.915320846836;8501.277391421674;8611;6036.0644100164545;6069.36271691466;6302.779402721067;6506.902396463962;6582.703778329891;10387.324823886423;10407.06336429725;10581.947090264499;10688.091512221548;10720.35677859331;10582.576696932036;10587.258891877495;10798.514066866852;10891.400972946247;10940.1794865387;7456.291652293024;7940.84118317642;8098.878516822135;8170.468919520212;8331.750047498585;8927.821443570961;9121.77195570116;9125.817483650422;9127.680531181377;9127.908720912706;7865;8080.0707918590115;8081.191880812107;8094.231048050426;8385.161483851565;8000.639971201426;8002.159773223961;8094.757157285291;8143.470674406145;8257.545834979133;6607.108040414675;6897.379235868796;7032;7161.355478007004;7163.677634506355;5127.743612819419;5326;5419.864503387447;5461.508464238238;5528.336599608073;5322.254884316276;5378.19327100938;5455.8363249102595;5473.370562385377;5677.687727175045 22:33:31 INFO - PROCESS | 11455 | |4;prototype.html;313.6330493322812;315.6275594798138;321.8471226167571;326.54447046370314;327.2065241788662;5226.634135610509;5247.790088396469;5261.868453288665;5262.184361423976;5290.761915713792;5247.763850626721;5254.553362964143;5267.28891599634;5282.31329927109;5294.947050529494;3938.3304838177505;4011.959880401188;4099.651529619986;4126.50211269716;4152.896177595554;4095.815688294034;4116.242117781079;4132.483439570054;4135.400366946791;4159.500859896817;4076.8165432555606;4083;4094.9181016379653;4099.508059032905;4114.8148333324925;3931.5282166140105;3949.4273330367223;4092.1789803537436;4120.484939382577;4134.875953721393;3610.1696609779633;3616.258666973264;3618.3486972344967;3624.7825130492256;3632.9636703633155;5164.974175129138;5180.974095129538;5190.143626301656;5199.688018718889;5217.399999000126;4418;4643.374818813422;4652.418447694038;4685.8594242172785;4755.262934245197;5458.808941687062;5465.180222966547;5470.5350045246205;5475.780968761284;5482.506574408282;3870.935492739491;3931.3709806430834;3932.58707835676;3935.3506671399186;3943.72393932422;5717.714114294268;5728.942710572883;5740.110282906115;5742.219911827388;5747.856303592402;4102.938455923104;4107.322291821816;4122.113745544722;4122.855700050543;4136.834526618902;4103.302438585448;4204.348326009474;4212.178625168062;4212.936805947913;4213.936790948138;4702.741349225826;4721.834735784231;4722.150012997625;4736.573708366263;4740.440162312381;5453.672779633234;5465.289512363447;5486.174620070667;5522.22688823565;5595.804146854841;1411.6823714664324;1426.2227086238024;1431.3201229416152;1442.9927850360682;1444.306732768277;926.4395041000232;936.7564433247405;985.2905907746411;991.4943378876825;1004.1063453526367;5239.895202095937;5369.892602147936;5387.353517577916;5401.648892821954;5404.405515393303;5702.486776190163;5716.599838011382;5730.5129064030025;5748.815450128925;5769.221155144086;5232.73836308183;5293.638539527351;5301.708406037628;5325.334333208349;5354.170103633943;2961.2448825549345;2995.5735853958363;2999.2154667972522;3001.444732724453;3002.2044158298067;5785.768569257267;5807.216025836543;5811;5813.168851811666;5823.272090988627;5503.724813759296;5764.654120752768;5772.682502462405;5792.565557583198;5806.809604031164;5757.913631295534;5759.942400576108;5780.16187652788;5786.247787787561;5794.449527294901;5392.9237243662255;5616.157576363578;5618.101103823368;5629.802956896653;5634.85912852182;5563.77744890216;5569.554435645057;5574.91637625444;5585.00672019508;5592.3848376677715;5768.3078030634915;5791.536677065909;5792.855178620568;5805.970970145291;5813.79651712188;3953.288408086572;4072.674186065167;4074.5517993020153;4098.91802163961;4100.528439229527;5601.439856014366;5634.788520468119;5648.870225954907;5657.207990881362;5669.461401166883;5511.22842802;5513.200585914939;5524.91712624319;5548.44771405142;5564.247262112422;5218.7129707864915;5425.593080518898;5429.484199001088;5433.212184233343;5443.727813609383;5382.165764306539;5408.188771684278;5425.728713564385;5445.319335083114;5482.205080263419;5300.3639563252655;5338.839834804962;5446.591505637014;5472.72636368188;5492.340919089735;5362.249285100167;5374.758135883816;5409.026375252335;5409.161579955114;5451.155070964007 22:33:31 INFO - PROCESS | 11455 | |5;yui.html;758.2758465665287;770.2567022822975;775.298354988735;785.5129819511903;801.2388231180378;16416.589585260375;17135.742963855522;17143;17176.91411542942;17254;14363.425462981495;15567.53297401077;16195.757063644054;16395.098269595168;16913.069781162063;797.9561124138185;813.6582635293191;816.3754727633366;818.2104269380047;819.0744458761591;662.496502657981;775.2983549887333;786.5202226641744;795.757294025322;806.1414593457978;856.5289090999925;866;867.3408209760596;867.371155911965;868.0624925080898;862.3833958719483;864.269692110169;865.247234905633;865.4980111535294;866.2420382165606;505.5652139160319;599.9790007349721;614.0727501472763;617.5769597825504;631.9968400158016;15581.532554023284;15667.764983525256;15705.52883413488;15710.842891571052;15722.764158537517;14131.929340353336;14238.85761142396;14574.198419086946;14702.705945881129;15175.468858590008;13907.165570065828;13918.117609634703;13945;14170;14227.573172804832;4498.110793466719;4546.52261512543;4551.317302404633;4570;4658.138244424792;84582.57708711478;85095.57452212762;85452;87839.56080219558;88355;11758.764824703629;11809.114316426303;11918.821217681741;11919.165658404001;12148.635540933787;21638.809865457235;21910;21960.780392195873;21967.560648787254;22051.66922496164;8508.361872859561;8564.400491965627;8695.565221739014;8941.821163576693;9134.223590994707;4790.688605240648;4815.3499277597775;4824.782884770194;4827.300041493963;4990.600751939836;3674.210044840319;3839.3665045267776;3841.2701586698436;3842.1547259602844;3842.7502212356108;685.9451243900576;689.8447849233883;717.7667258141125;726.9273072692794;729.4893574497872;669.8626781509779;757.2730179028194;779.3531368963746;787.9251471110235;799.9680012799423;9961.850572241423;10513.053825155772;10514.421706806199;10552.683419497427;10599.470026498644;751.8909608327782;775.441681988967;775.7789030126387;775.8874963130314;781.9100803407567;4840.7337596432535;4958.479359667255;4985.25250109611;5007.148784706608;5016.623753218523;71650;91655.54172229096;104985.17596356213;128025;133789;108485;109574;111002.44498777455;111441;111861;59042.40957590455;61450.38549614447;81289.59355203068;94949.52525237329;97107.51446242865;8889.4666320021;9224.723258302261;9351.953240233755;9355.4386736796;9389.718308450758;54040.729796350766;55222.723886380314;55458.16812747893;56701;56807.43192568187;77327.226727732;84179.5791021041;84204.57897710473;84866;87067;12407.773978765405;12414.56549020816;12517.374131293582;12614.810777838524;12673.93663031679;13846.86153138496;14266.714665706835;14983.550493485212;15096.39614415411;15701.450449234222;12781.808272875729;13023.739525209254;13217.34138942899;13239.735205296032;13648.454061837416;13249.735005300034;13647.38586763618;13651.385687644279;13782.65543361424;14040.297985100908;11954.19504528125;11976.880231197576;12414.379281036092;12438.19151755133;12510.935695610122;10999.61501347449;11148.219624626523;11173.441327933408;11325.15061370417;11698.532058717556;11090.778184436427;11225.540679711585;11293.774124517628;11319.434028298718;11541.769164616828 22:33:31 INFO - PROCESS | 11455 | __end_tp_report 22:33:31 INFO - PROCESS | 11455 | __start_cc_report 22:33:31 INFO - PROCESS | 11455 | _x_x_mozilla_cycle_collect,901 22:33:31 INFO - PROCESS | 11455 | __end_cc_report 22:33:31 INFO - PROCESS | 11455 | __startTimestamp1472621611228__endTimestamp 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | ------- Summary: start ------- 22:33:31 INFO - PROCESS | 11455 | Number of tests: 6 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | [#0] dojo.html Cycles:165 Average:5314.79 Median:5498.73 stddev:603.64 (11.0%) stddev-sans-first:576.16 22:33:31 INFO - PROCESS | 11455 | Values: 2945.1 3003.9 3008.9 3031.3 3061.4 5243.1 5244.3 5301.8 5359.9 5371.5 5300.5 5303.4 5374.8 5382.1 5429.0 4766.5 4819.0 4821.0 4826.6 5052.2 4914.3 4939.8 4964.8 4984.8 4987.3 4759.5 4807.0 4814.4 4847.1 4888.4 4808.8 4818.8 4893.1 4895.3 4906.2 4816.1 4845.9 4852.1 4881.0 4895.3 5138.6 5376.2 5385.9 5387.5 5396.9 5631.0 5646.7 5712.7 5727.8 5733.2 5722.3 5751.9 5759.2 5775.4 5782.1 5239.9 5312.2 5394.6 5394.8 5406.5 5837.9 5856.6 5859.9 5928.7 5934.7 5628.2 5630.4 5632.6 5634.3 5637.6 5526.6 5683.5 5692.5 5692.8 5709.6 5700.8 5786.9 5787.1 5792.1 5795.6 5688.3 5733.5 5736.5 5739.7 5797.0 4406.0 4452.1 4453.2 4455.1 4504.1 3918.7 3943.1 3968.7 3975.5 3996.4 5459.8 5480.2 5497.8 5498.7 5551.9 5783.2 5788.4 5793.8 5798.0 5798.7 5289.3 5316.1 5362.1 5365.2 5453.4 4918.9 4921.8 4923.5 4924.7 4972.5 5828.3 5869.2 5869.6 5882.8 5927.1 5615.9 5669.7 5681.0 5686.2 5697.9 5809.5 5825.6 5833.4 5890.9 5905.9 5488.1 5532.3 5551.1 5552.1 5567.4 5622.4 5668.3 5679.2 5718.7 5764.5 5653.3 5666.4 5670.6 5705.1 5746.3 5586.4 5646.4 5649.2 5650.2 5654.0 5434.7 5450.0 5485.9 5572.8 5580.7 5565.5 5632.6 5635.6 5684.7 5698.3 5425.5 5430.5 5438.5 5440.5 5443.5 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | [#1] ext.html Cycles:180 Average:61503.19 Median:40101.70 stddev:122735.20 (306.1%) stddev-sans-first:122994.18 22:33:31 INFO - PROCESS | 11455 | Values: 548.9 566.6 596.2 598.6 600.8 32176.0 35813.5 35879.0 42644.0 43952.8 44987.3 45273.1 45559.8 45617.5 45675.3 8942.2 9071.8 9093.8 9095.8 9097.3 8230.3 8410.9 8882.6 10591.9 10840.0 11046.4 11069.5 11124.0 11129.4 11140.9 10876.9 10899.4 10903.5 10919.1 10924.9 8083.2 8293.5 8307.5 10599.1 10791.4 41605.4 42463.8 42538.0 46232.0 46253.3 3463.5 3774.5 4148.6 4187.7 4196.3 31746.4 32658.0 32762.5 32763.0 33003.3 4340.9 4410.1 4548.2 4548.9 4642.1 346503.0 348251.0 348502.0 348503.3 349128.0 57009.4 57328.9 57562.0 57849.4 58358.4 60762.4 60885.0 61057.0 61062.4 61170.4 15920.6 16241.6 16268.2 16269.1 16272.3 11577.2 11603.3 11618.4 11622.5 11624.9 14621.9 16337.3 16570.6 16779.2 16837.8 5711.9 5960.6 6095.1 6141.2 6238.5 910.9 975.6 1008.7 1027.7 1030.5 31565.4 31618.5 31624.3 31919.0 32467.0 1060.6 1087.9 1110.8 1157.5 1186.8 15539.5 16512.4 16742.0 16789.8 17062.8 551594.0 552811.0 555373.0 558093.0 559771.0 56412.4 56528.4 56622.7 56801.4 56971.7 1309.2 1318.3 1329.8 1343.9 1350.1 29666.7 30800.5 31857.4 31910.8 32108.5 50464.7 51019.7 51021.2 51124.7 51129.2 458000.0 460691.0 460776.0 461222.0 461322.0 36615.0 38230.3 39175.8 41639.6 42029.4 46502.8 46791.3 46929.0 47047.0 47290.0 39227.2 39983.0 40716.6 40960.6 42518.0 40126.0 40143.0 40909.6 41009.8 41045.0 39381.2 39948.8 40061.0 40077.4 40219.8 33875.8 35013.8 36756.0 37116.8 37162.6 26978.5 27900.3 27932.9 29187.0 29544.6 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | [#2] jquery.html Cycles:180 Average:28091.00 Median:390.22 stddev:75919.44 (19455.6%) stddev-sans-first:76103.91 22:33:31 INFO - PROCESS | 11455 | Values: 382.9 397.6 412.1 416.6 418.5 15438.2 18833.8 18860.9 19094.9 19839.8 18971.7 19044.2 19506.0 19609.4 19769.0 5256.9 5507.3 5709.3 5714.2 5757.9 5575.5 5666.8 5699.3 5739.3 5741.2 5391.5 5662.1 5690.1 5692.9 5695.0 5651.0 5666.3 5709.4 5715.1 5718.1 4937.7 5095.2 5123.6 5130.5 5136.6 23506.6 23608.0 23692.3 23705.3 23922.0 2442.3 2559.8 2646.5 3000.3 3066.9 13852.4 14732.6 14735.3 14926.0 15382.5 1948.7 1990.3 1999.6 2024.2 2044.8 65758.0 67881.3 70359.0 71627.0 78162.0 10242.9 10723.5 11021.9 11055.9 11056.7 15944.2 15957.8 15959.9 15991.8 15993.0 7699.9 7954.0 7976.0 8070.4 8097.6 7308.2 7314.9 7423.5 7634.7 7638.8 4649.0 4765.5 4826.4 4913.5 4963.8 1968.6 2013.9 2024.2 2058.8 2066.6 847.6 928.4 959.6 970.0 989.9 17551.3 18275.2 18847.3 19044.2 19293.7 955.9 956.6 979.7 1003.7 1021.6 10543.3 10813.8 10899.5 10909.3 10929.1 407724.8 427248.0 479072.0 488876.0 490032.0 94155.5 94396.5 95610.0 96511.5 96769.5 982.3 1023.1 1044.9 1047.5 1053.3 16550.3 16841.5 17310.6 17879.8 17944.2 42877.6 42989.6 44961.6 45137.5 45423.5 55473.7 55576.4 57065.4 58645.0 62758.0 8401.4 8518.7 8554.1 8732.4 8821.3 9464.0 13290.1 13530.3 13820.9 14018.1 11519.5 11664.8 11788.0 11935.8 12443.3 11170.9 11543.5 11700.8 11789.9 11822.2 11430.7 11542.7 11972.0 12033.4 12066.5 8286.9 8522.5 8641.5 8836.9 8928.7 10853.8 10946.4 11102.1 11358.5 11359.1 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | [#3] mootools.html Cycles:180 Average:6038.61 Median:2591.47 stddev:3469.78 (133.9%) stddev-sans-first:3461.58 22:33:31 INFO - PROCESS | 11455 | Values: 1344.9 1469.1 1501.7 1630.3 1789.8 8623.3 8987.6 9065.8 9301.9 9320.5 9048.4 9161.6 9165.5 9169.9 9170.7 2173.6 2426.0 2506.8 2527.4 2536.1 2526.1 2558.6 2559.4 2561.7 2563.5 2429.2 2452.0 2468.8 2482.5 2489.2 2326.6 2419.8 2428.4 2442.1 2447.8 2430.6 2458.9 2474.6 2477.8 2480.0 9038.0 9235.4 9236.7 9240.3 9243.2 5463.8 5592.6 5600.7 5601.0 5609.7 3423.6 3529.3 3659.3 3710.7 3725.9 1207.4 1213.4 1217.9 1250.6 1255.0 12886.2 13022.2 13025.4 13029.1 13047.9 4707.5 4772.2 4783.9 4909.2 4955.7 10088.8 10107.6 10111.5 10118.3 10131.0 5571.4 5573.3 5573.5 5577.8 5581.0 4582.6 4612.8 5057.6 5178.5 5210.1 3975.6 3977.6 4117.8 4128.9 4131.9 1219.6 1249.9 1256.8 1262.3 1274.9 815.3 930.2 946.7 952.3 964.5 6015.4 6195.6 6199.1 6216.5 6296.4 458.7 464.1 469.6 475.4 475.5 4093.9 4095.9 4109.4 4119.8 4124.8 11327.7 11510.0 11624.7 11680.8 11687.9 11522.4 11542.5 11545.8 11550.4 11569.3 8112.6 8464.1 8467.9 8501.3 8611.0 6036.1 6069.4 6302.8 6506.9 6582.7 10387.3 10407.1 10581.9 10688.1 10720.4 10582.6 10587.3 10798.5 10891.4 10940.2 7456.3 7940.8 8098.9 8170.5 8331.8 8927.8 9121.8 9125.8 9127.7 9127.9 7865.0 8080.1 8081.2 8094.2 8385.2 8000.6 8002.2 8094.8 8143.5 8257.5 6607.1 6897.4 7032.0 7161.4 7163.7 5127.7 5326.0 5419.9 5461.5 5528.3 5322.3 5378.2 5455.8 5473.4 5677.7 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | [#4] prototype.html Cycles:180 Average:4644.56 Median:5301.04 stddev:1355.74 (25.6%) stddev-sans-first:1319.99 22:33:31 INFO - PROCESS | 11455 | Values: 313.6 315.6 321.8 326.5 327.2 5226.6 5247.8 5261.9 5262.2 5290.8 5247.8 5254.6 5267.3 5282.3 5294.9 3938.3 4012.0 4099.7 4126.5 4152.9 4095.8 4116.2 4132.5 4135.4 4159.5 4076.8 4083.0 4094.9 4099.5 4114.8 3931.5 3949.4 4092.2 4120.5 4134.9 3610.2 3616.3 3618.3 3624.8 3633.0 5165.0 5181.0 5190.1 5199.7 5217.4 4418.0 4643.4 4652.4 4685.9 4755.3 5458.8 5465.2 5470.5 5475.8 5482.5 3870.9 3931.4 3932.6 3935.4 3943.7 5717.7 5728.9 5740.1 5742.2 5747.9 4102.9 4107.3 4122.1 4122.9 4136.8 4103.3 4204.3 4212.2 4212.9 4213.9 4702.7 4721.8 4722.2 4736.6 4740.4 5453.7 5465.3 5486.2 5522.2 5595.8 1411.7 1426.2 1431.3 1443.0 1444.3 926.4 936.8 985.3 991.5 1004.1 5239.9 5369.9 5387.4 5401.6 5404.4 5702.5 5716.6 5730.5 5748.8 5769.2 5232.7 5293.6 5301.7 5325.3 5354.2 2961.2 2995.6 2999.2 3001.4 3002.2 5785.8 5807.2 5811.0 5813.2 5823.3 5503.7 5764.7 5772.7 5792.6 5806.8 5757.9 5759.9 5780.2 5786.2 5794.4 5392.9 5616.2 5618.1 5629.8 5634.9 5563.8 5569.6 5574.9 5585.0 5592.4 5768.3 5791.5 5792.9 5806.0 5813.8 3953.3 4072.7 4074.6 4098.9 4100.5 5601.4 5634.8 5648.9 5657.2 5669.5 5511.2 5513.2 5524.9 5548.4 5564.2 5218.7 5425.6 5429.5 5433.2 5443.7 5382.2 5408.2 5425.7 5445.3 5482.2 5300.4 5338.8 5446.6 5472.7 5492.3 5362.2 5374.8 5409.0 5409.2 5451.2 22:33:31 INFO - PROCESS | 11455 | 22:33:31 INFO - PROCESS | 11455 | [#5] yui.html Cycles:180 Average:21506.50 Median:4614.07 stddev:31105.33 (674.1%) stddev-sans-first:31153.57 22:33:31 INFO - PROCESS | 11455 | Values: 758.3 770.3 775.3 785.5 801.2 16416.6 17135.7 17143.0 17176.9 17254.0 14363.4 15567.5 16195.8 16395.1 16913.1 798.0 813.7 816.4 818.2 819.1 662.5 775.3 786.5 795.8 806.1 856.5 866.0 867.3 867.4 868.1 862.4 864.3 865.2 865.5 866.2 505.6 600.0 614.1 617.6 632.0 15581.5 15667.8 15705.5 15710.8 15722.8 14131.9 14238.9 14574.2 14702.7 15175.5 13907.2 13918.1 13945.0 14170.0 14227.6 4498.1 4546.5 4551.3 4570.0 4658.1 84582.6 85095.6 85452.0 87839.6 88355.0 11758.8 11809.1 11918.8 11919.2 12148.6 21638.8 21910.0 21960.8 21967.6 22051.7 8508.4 8564.4 8695.6 8941.8 9134.2 4790.7 4815.3 4824.8 4827.3 4990.6 3674.2 3839.4 3841.3 3842.2 3842.8 685.9 689.8 717.8 726.9 729.5 669.9 757.3 779.4 787.9 800.0 9961.9 10513.1 10514.4 10552.7 10599.5 751.9 775.4 775.8 775.9 781.9 4840.7 4958.5 4985.3 5007.1 5016.6 71650.0 91655.5 104985.2 128025.0 133789.0 108485.0 109574.0 111002.4 111441.0 111861.0 59042.4 61450.4 81289.6 94949.5 97107.5 8889.5 9224.7 9352.0 9355.4 9389.7 54040.7 55222.7 55458.2 56701.0 56807.4 77327.2 84179.6 84204.6 84866.0 87067.0 12407.8 12414.6 12517.4 12614.8 12673.9 13846.9 14266.7 14983.6 15096.4 15701.5 12781.8 13023.7 13217.3 13239.7 13648.5 13249.7 13647.4 13651.4 13782.7 14040.3 11954.2 11976.9 12414.4 12438.2 12510.9 10999.6 11148.2 11173.4 11325.2 11698.5 11090.8 11225.5 11293.8 11319.4 11541.8 22:33:31 INFO - PROCESS | 11455 | -------- Summary: end -------- 22:33:31 INFO - PROCESS | 11455 | 22:33:32 INFO - TEST-INFO | 11455: exit 0 22:33:32 INFO - TEST-OK | dromaeo_css | took 1115430ms 22:33:32 INFO - TEST-START | kraken 22:33:32 INFO - operating with platform_type : linux_ 22:33:32 INFO - Initialising browser for kraken test... 22:33:32 INFO - TEST-INFO | started process 11765 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWQDyrK/profile http://localhost:43320/getInfo.html) 22:33:36 INFO - PROCESS | 11765 | __metrics Screen width/height:1600/1200 22:33:36 INFO - PROCESS | 11765 | colorDepth:24 22:33:36 INFO - PROCESS | 11765 | Browser inner width/height: 1024/697 22:33:36 INFO - PROCESS | 11765 | __metrics 22:33:36 INFO - PROCESS | 11765 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:33:36 INFO - PROCESS | 11765 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:33:37 INFO - PROCESS | 11765 | console.error: PushService: 22:33:37 INFO - PROCESS | 11765 | stateChangeProcessEnqueue: Error transitioning state 22:33:37 INFO - PROCESS | 11765 | UnknownError 22:33:37 INFO - TEST-INFO | 11765: exit 0 22:33:37 INFO - Browser initialized. 22:33:37 INFO - Running cycle 1/1 for kraken test... 22:33:37 INFO - TEST-INFO | started process 11848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWQDyrK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:33:49 INFO - PROCESS | 11848 | RSS: Main: 180379648 22:33:49 INFO - PROCESS | 11848 | 22:33:52 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43320/tests/kraken/driver.html?testName=audio-beat-detection) 22:33:53 INFO - PROCESS | 11848 | RSS: Main: 234262528 22:33:53 INFO - PROCESS | 11848 | 22:33:55 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43320/tests/kraken/driver.html?testName=audio-dft) 22:33:56 INFO - PROCESS | 11848 | RSS: Main: 225021952 22:33:56 INFO - PROCESS | 11848 | 22:33:59 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43320/tests/kraken/driver.html?testName=audio-fft) 22:34:00 INFO - PROCESS | 11848 | RSS: Main: 301920256 22:34:00 INFO - PROCESS | 11848 | 22:34:02 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43320/tests/kraken/driver.html?testName=audio-oscillator) 22:34:02 INFO - PROCESS | 11848 | RSS: Main: 211853312 22:34:02 INFO - PROCESS | 11848 | 22:34:05 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:34:05 INFO - PROCESS | 11848 | RSS: Main: 213815296 22:34:05 INFO - PROCESS | 11848 | 22:34:08 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43320/tests/kraken/driver.html?testName=imaging-darkroom) 22:34:09 INFO - PROCESS | 11848 | RSS: Main: 252657664 22:34:09 INFO - PROCESS | 11848 | 22:34:12 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43320/tests/kraken/driver.html?testName=imaging-desaturate) 22:34:13 INFO - PROCESS | 11848 | RSS: Main: 251052032 22:34:13 INFO - PROCESS | 11848 | 22:34:16 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43320/tests/kraken/driver.html?testName=json-parse-financial) 22:34:16 INFO - PROCESS | 11848 | RSS: Main: 250916864 22:34:16 INFO - PROCESS | 11848 | 22:34:18 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:34:18 INFO - PROCESS | 11848 | RSS: Main: 214155264 22:34:18 INFO - PROCESS | 11848 | 22:34:20 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:34:21 INFO - PROCESS | 11848 | RSS: Main: 219795456 22:34:21 INFO - PROCESS | 11848 | 22:34:23 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:34:24 INFO - PROCESS | 11848 | RSS: Main: 221843456 22:34:24 INFO - PROCESS | 11848 | 22:34:26 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:34:27 INFO - PROCESS | 11848 | RSS: Main: 224047104 22:34:27 INFO - PROCESS | 11848 | 22:34:29 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:34:30 INFO - PROCESS | 11848 | RSS: Main: 221233152 22:34:30 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | Cycle 1(1): loaded http://localhost:43320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43320/tests/kraken/driver.html?testName=ai-astar) 22:34:32 INFO - PROCESS | 11848 | RSS: Main: 272343040 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | __start_tp_report 22:34:32 INFO - PROCESS | 11848 | _x_x_mozilla_page_load 22:34:32 INFO - PROCESS | 11848 | _x_x_mozilla_page_load_details 22:34:32 INFO - PROCESS | 11848 | |i|pagename|runs| 22:34:32 INFO - PROCESS | 11848 | |0;ai-astar;114;113;131;112;116;110;112;112;110;110 22:34:32 INFO - PROCESS | 11848 | |1;audio-beat-detection;147;119;150;180;119;143;153;125;173;121 22:34:32 INFO - PROCESS | 11848 | |2;audio-dft;160;162;161;163;163;167;162;163;161;162 22:34:32 INFO - PROCESS | 11848 | |3;audio-fft;76;77;76;179;76;75;75;77;174;76 22:34:32 INFO - PROCESS | 11848 | |4;audio-oscillator;82;83;148;84;83;83;144;84;83;142 22:34:32 INFO - PROCESS | 11848 | |5;imaging-gaussian-blur;116;117;117;117;117;117;118;118;117;118 22:34:32 INFO - PROCESS | 11848 | |6;imaging-darkroom;146;147;146;145;146;146;147;145;146;145 22:34:32 INFO - PROCESS | 11848 | |7;imaging-desaturate;96;96;95;96;95;96;95;96;96;95 22:34:32 INFO - PROCESS | 11848 | |8;json-parse-financial;53;52;53;53;54;52;52;52;53;52 22:34:32 INFO - PROCESS | 11848 | |9;json-stringify-tinderbox;62;63;63;63;63;63;63;63;62;63 22:34:32 INFO - PROCESS | 11848 | |10;stanford-crypto-aes;79;80;84;79;82;89;81;79;79;87 22:34:32 INFO - PROCESS | 11848 | |11;stanford-crypto-ccm;126;127;127;126;127;134;131;131;158;129 22:34:32 INFO - PROCESS | 11848 | |12;stanford-crypto-pbkdf2;169;169;165;166;168;169;165;161;161;164 22:34:32 INFO - PROCESS | 11848 | |13;stanford-crypto-sha256-iterative;69;69;65;65;66;69;65;70;66;65 22:34:32 INFO - PROCESS | 11848 | __end_tp_report 22:34:32 INFO - PROCESS | 11848 | __start_cc_report 22:34:32 INFO - PROCESS | 11848 | _x_x_mozilla_cycle_collect,1700 22:34:32 INFO - PROCESS | 11848 | __end_cc_report 22:34:32 INFO - PROCESS | 11848 | __startTimestamp1472621672527__endTimestamp 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | ------- Summary: start ------- 22:34:32 INFO - PROCESS | 11848 | Number of tests: 14 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#0] ai-astar Cycles:10 Average:114.00 Median:112.50 stddev:6.27 (5.6%) stddev-sans-first:6.65 22:34:32 INFO - PROCESS | 11848 | Values: 114.0 113.0 131.0 112.0 116.0 110.0 112.0 112.0 110.0 110.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:148.50 stddev:22.10 (14.9%) stddev-sans-first:23.39 22:34:32 INFO - PROCESS | 11848 | Values: 147.0 119.0 150.0 180.0 119.0 143.0 153.0 125.0 173.0 121.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.50 stddev:1.90 (1.2%) stddev-sans-first:1.80 22:34:32 INFO - PROCESS | 11848 | Values: 160.0 162.0 161.0 163.0 163.0 167.0 162.0 163.0 161.0 162.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#3] audio-fft Cycles:10 Average:96.10 Median:76.00 stddev:42.40 (55.8%) stddev-sans-first:44.34 22:34:32 INFO - PROCESS | 11848 | Values: 76.0 77.0 76.0 179.0 76.0 75.0 75.0 77.0 174.0 76.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:83.00 stddev:29.76 (35.9%) stddev-sans-first:30.71 22:34:32 INFO - PROCESS | 11848 | Values: 82.0 83.0 148.0 84.0 83.0 83.0 144.0 84.0 83.0 142.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 22:34:32 INFO - PROCESS | 11848 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 118.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 22:34:32 INFO - PROCESS | 11848 | Values: 146.0 147.0 146.0 145.0 146.0 146.0 147.0 145.0 146.0 145.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:34:32 INFO - PROCESS | 11848 | Values: 96.0 96.0 95.0 96.0 95.0 96.0 95.0 96.0 96.0 95.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 22:34:32 INFO - PROCESS | 11848 | Values: 53.0 52.0 53.0 53.0 54.0 52.0 52.0 52.0 53.0 52.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 22:34:32 INFO - PROCESS | 11848 | Values: 62.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 62.0 63.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:81.50 stddev:3.63 (4.5%) stddev-sans-first:3.70 22:34:32 INFO - PROCESS | 11848 | Values: 79.0 80.0 84.0 79.0 82.0 89.0 81.0 79.0 79.0 87.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#11] stanford-crypto-ccm Cycles:10 Average:131.60 Median:130.00 stddev:9.64 (7.4%) stddev-sans-first:10.01 22:34:32 INFO - PROCESS | 11848 | Values: 126.0 127.0 127.0 126.0 127.0 134.0 131.0 131.0 158.0 129.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:167.00 stddev:3.09 (1.9%) stddev-sans-first:3.04 22:34:32 INFO - PROCESS | 11848 | Values: 169.0 169.0 165.0 166.0 168.0 169.0 165.0 161.0 161.0 164.0 22:34:32 INFO - PROCESS | 11848 | 22:34:32 INFO - PROCESS | 11848 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:2.08 (3.1%) stddev-sans-first:2.06 22:34:32 INFO - PROCESS | 11848 | Values: 69.0 69.0 65.0 65.0 66.0 69.0 65.0 70.0 66.0 65.0 22:34:32 INFO - PROCESS | 11848 | -------- Summary: end -------- 22:34:32 INFO - PROCESS | 11848 | 22:34:33 INFO - TEST-INFO | 11848: exit 0 22:34:33 INFO - TEST-OK | kraken | took 61555ms 22:34:33 INFO - SUITE-END | took 1176s 22:34:34 INFO - Completed test suite (00:19:37) 22:34:34 INFO - javascript benchmark 22:34:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2945.116465060482, 3003.8798448062066, 3008.8796448142075, 3031.3028003559193, 3061.3724186541763, 5243.10867152584, 5244.344456942882, 5301.787928482856, 5359.89280214396, 5371.543418809401, 5300.496452836984, 5303.390110137336, 5374.838754837351, 5382.085045542258, 5428.995635807379, 4766.475687674361, 4819.036192761444, 4820.951790482085, 4826.638002149835, 5052.191649336107, 4914.287428322891, 4939.753012349386, 4964.826231081913, 4984.75076246188, 4987.251912213161, 4759.5002524734755, 4806.975965120169, 4814.398200224972, 4847.103285892122, 4888.364512613355, 4808.831690890802, 4818.7831547580445, 4893.0703166398625, 4895.265710143472, 4906.215005599122, 4816.060868130716, 4845.878853028667, 4852.053849499349, 4880.975595122037, 4895.339129217546, 5138.640295179339, 5376.193570964348, 5385.865353366158, 5387.4612538746205, 5396.91904621431, 5631.0, 5646.717664116819, 5712.742926568314, 5727.799527016535, 5733.226014488033, 5722.256106706143, 5751.8562035949435, 5759.164921086461, 5775.364709881909, 5782.132680097977, 5239.947600524022, 5312.203169524602, 5394.649347792381, 5394.78420863169, 5406.54044406226, 5837.88324233513, 5856.6486010838635, 5859.912101318484, 5928.703564821742, 5934.703264836741, 5628.183913332544, 5630.40880707528, 5632.605717599728, 5634.32388113429, 5637.577181711388, 5526.613137080446, 5683.460071293303, 5692.516136128475, 5692.829215123553, 5709.628874123168, 5700.8004719835435, 5786.929418057673, 5787.074068149076, 5792.102224155263, 5795.623284486495, 5688.288963879515, 5733.512651424676, 5736.4550367715, 5739.684317362502, 5796.971015144897, 4405.955940440619, 4452.131834292347, 4453.198424283595, 4455.131249406335, 4504.07666428388, 3918.74528155669, 3943.0930885896405, 3968.7023473239624, 3975.5030621172355, 3996.440498330236, 5459.754311055933, 5480.232767412565, 5497.780088796483, 5498.725063746797, 5551.916721249185, 5783.161441590946, 5788.392218816964, 5793.826185214451, 5797.971010144923, 5798.6810725410505, 5289.312389389354, 5316.149416093406, 5362.088444964365, 5365.195220716923, 5453.4273901240595, 4918.926216106761, 4921.753912304371, 4923.458419573844, 4924.704517728947, 4972.5027497249985, 5828.271466066826, 5869.178315035899, 5869.589128761031, 5882.794102206402, 5927.051671732502, 5615.859603509945, 5669.744861481326, 5681.034224181982, 5686.175504551734, 5697.886042279214, 5809.477147056785, 5825.563082768894, 5833.387494313036, 5890.911636325544, 5905.911411328748, 5488.121900496061, 5532.30846144232, 5551.139573366135, 5552.083906155439, 5567.387587365468, 5622.409646987007, 5668.3198016236665, 5679.233303504139, 5718.742656580381, 5764.481196692318, 5653.321601407857, 5666.405027472221, 5670.574706897081, 5705.115707065412, 5746.339170995389, 5586.385497595343, 5646.35066967304, 5649.180868774087, 5650.237217975685, 5654.038813401649, 5434.673919564839, 5449.972750136224, 5485.945140548543, 5572.777088916398, 5580.720963951705, 5565.499105080562, 5632.5775566831835, 5635.577331700057, 5684.7157642118555, 5698.31620205578, 5425.538829199562, 5430.456954304538, 5438.510534051796, 5440.483154100354, 5443.537299329601], "value": 5273.367447222373, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.923150758894, 566.5694072504896, 596.1802521532892, 598.6049207523035, 600.7927265093541, 32176.0, 35813.46279805802, 35879.0, 42644.0, 43952.78023609878, 44987.325190122174, 45273.09453810922, 45559.77220113903, 45617.54382456175, 45675.314870276976, 8942.150495702914, 9071.818563628723, 9093.818123637557, 9095.818083638324, 9097.317701172438, 8230.2592766651, 8410.915890841105, 8882.600282987281, 10591.894081059207, 10840.0, 11046.392448415334, 11069.50187241576, 11124.0, 11129.443527823616, 11140.888591114066, 10876.945615271912, 10899.400532970683, 10903.454827258605, 10919.12646988239, 10924.890751092546, 8083.151269116775, 8293.502389856629, 8307.501549907025, 10599.0990765785, 10791.406472644001, 41605.375919361235, 42463.78768106171, 42538.0, 46232.0, 46253.30620040668, 3463.515107884898, 3774.471573979645, 4148.56440073794, 4187.706860519765, 4196.307609244471, 31746.365072698416, 32658.0, 32762.508562371582, 32763.0, 33003.339933201445, 4340.891477713051, 4410.095930334273, 4548.20406428877, 4548.863534093983, 4642.0715856828765, 346503.0, 348251.0, 348502.0, 348503.2574837135, 349128.0, 57009.42990570041, 57328.85342293131, 57562.0, 57849.421505784405, 58358.416415836145, 60762.39237607567, 60884.95575221221, 61057.0, 61062.389376106556, 61170.38829611647, 15920.601984950468, 16241.59396015109, 16268.186590670419, 16269.10519921392, 16272.349106035863, 11577.189596728316, 11603.303801771921, 11618.419079046014, 11622.476988535536, 11624.88375116255, 14621.926890365692, 16337.346506139858, 16570.58573535647, 16779.161041947853, 16837.831621683872, 5711.857203569861, 5960.552958528127, 6095.116208149793, 6141.232345956755, 6238.469730072995, 910.913463220993, 975.6341371985505, 1008.6570566007592, 1027.7430642339416, 1030.469308306223, 31565.36869262602, 31618.525722114187, 31624.256361545493, 31919.0, 32467.0, 1060.5863713151878, 1087.8912108789057, 1110.8167152419921, 1157.473349625935, 1186.7982442984714, 15539.45611903578, 16512.42206522766, 16742.0, 16789.83210167883, 17062.829371706124, 551594.0, 552811.0, 555373.0, 558093.0, 559771.0, 56412.43587564072, 56528.43471565314, 56622.71688641531, 56801.431985679614, 56971.71514142403, 1309.214471317202, 1318.2815365625756, 1329.8271224740722, 1343.932803359828, 1350.0684527675871, 29666.703332967256, 30800.53799192969, 31857.362852743277, 31910.84044579762, 32108.518372223967, 50464.74767626138, 51019.744901275255, 51021.234681479065, 51124.74437627788, 51129.23306150485, 458000.0, 460691.0, 460776.0, 461222.0, 461322.0, 36615.0, 38230.3113896338, 39175.80412097921, 41639.58360416357, 42029.36955945602, 46502.76748616235, 46791.29813052875, 46929.0, 47047.0, 47290.0, 39227.215455691294, 39983.0, 40716.59283407128, 40960.59039409568, 42518.0, 40126.0, 40143.0, 40909.590904090575, 41009.794951025055, 41045.0, 39381.212375751755, 39948.80274114539, 40061.0, 40077.398839018024, 40219.798901005306, 33875.83062084674, 35013.824930875184, 36756.0, 37116.81441592883, 37162.62837371592, 26978.460430791667, 27900.30249243704, 27932.86033569819, 29187.0, 29544.556831647973], "value": 20325.91704356288, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8659969010845, 397.5726094448467, 412.0933945320295, 416.55636746864593, 418.493622716513, 15438.150901700403, 18833.811661883377, 18860.905695471538, 19094.90452547737, 19839.801601983974, 18971.71542426865, 19044.238230470764, 19506.0, 19609.411717648458, 19769.0, 5256.947430525704, 5507.284053073095, 5709.343425506057, 5714.228579141804, 5757.913631295534, 5575.526080283181, 5666.773329066832, 5699.287589051369, 5739.282589676291, 5741.224934633834, 5391.514763671269, 5662.065759149736, 5690.146478028309, 5692.8861422771515, 5695.0, 5650.971745141269, 5666.291713535808, 5709.429057094258, 5715.057015592443, 5718.056520674084, 4937.703737775745, 5095.210242412395, 5123.641345105844, 5130.53825155734, 5136.589072874199, 23506.64740028901, 23608.0, 23692.289231323088, 23705.288841334786, 23922.0, 2442.255112190781, 2559.8080143989278, 2646.470705858836, 3000.339925216449, 3066.9079927602206, 13852.376643051088, 14732.55802325932, 14735.336909839083, 14926.0, 15382.538523844303, 1948.7076938459202, 1990.263602467082, 1999.6400647883374, 2024.210557882427, 2044.7852975437663, 65758.0, 67881.32118678799, 70359.0, 71627.0, 78162.0, 10242.948785256174, 10723.463826808784, 11021.944890275498, 11055.889441105646, 11056.723581910355, 15944.202789860692, 15957.840421595636, 15959.920200399156, 15991.840081599268, 15993.04041757475, 7699.923000770033, 7954.0, 7976.0, 8070.354371650254, 8097.595120243965, 7308.159561650478, 7314.890276645855, 7423.48035637511, 7634.656440460192, 7638.847223055508, 4649.0, 4765.547273009059, 4826.359037927122, 4913.459519452857, 4963.751812409365, 1968.6161198566429, 2013.9295124670566, 2024.170090262985, 2058.8455865810124, 2066.5763518478675, 847.5846835050904, 928.3640706116332, 959.5586030426082, 969.9806003879883, 989.871316728835, 17551.297948081934, 18275.177617007266, 18847.340343087926, 19044.238230470626, 19293.710594341097, 955.8709574207393, 956.6316967967383, 979.6522234606755, 1003.6988903328972, 1021.5584752441671, 10543.26197166191, 10813.755873237997, 10899.455027248765, 10909.290896091728, 10929.071028962468, 407724.76825695275, 427248.0, 479072.0, 488876.0, 490032.0, 94155.52922235482, 94396.52801735948, 95609.96095409882, 96511.51744241234, 96769.51615241879, 982.3123813330698, 1023.1456733627356, 1044.8589440425446, 1047.4762618690654, 1053.2521909444226, 16550.33798648089, 16841.494755157364, 17310.5672358187, 17879.821201787814, 17944.19251133728, 42877.57122428861, 42989.57010429856, 44961.550384495735, 45137.548624513336, 45423.54576454193, 55473.72263138658, 55576.44423555713, 57065.42934570601, 58645.0, 62758.0, 8401.369897257608, 8518.744437666632, 8554.101819309131, 8732.388732788644, 8821.338399619926, 9464.0, 13290.136141150795, 13530.255835929118, 13820.93089534586, 14018.088824226392, 11519.481623326803, 11664.825027624762, 11788.0, 11935.82096268574, 12443.253404795742, 11170.944145279222, 11543.480543375736, 11700.765984680429, 11789.882101178879, 11822.231554948901, 11430.657080287605, 11542.653720388402, 11972.0, 12033.398330083635, 12066.457009434422, 8286.875696864672, 8522.531260780717, 8641.481511109354, 8836.867446988172, 8928.687495937611, 10853.837192441963, 10946.412770148443, 11102.111831053659, 11358.488868000795, 11359.09127269833], "value": 9271.795873444216, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.8587898270687, 1469.103846653541, 1501.7221813964418, 1630.2989714422802, 1789.7852257729076, 8623.267022303104, 8987.59555819988, 9065.818683626332, 9301.90698093019, 9320.533973301342, 9048.366614337, 9161.633534658622, 9165.495897725623, 9169.862452063207, 9170.679026234067, 2173.619616567102, 2425.9514809703796, 2506.8370555913853, 2527.36815796051, 2536.112360673768, 2526.0906073813503, 2558.6289987951736, 2559.398541342783, 2561.70540387855, 2563.47448773002, 2429.2105065853666, 2451.9754802451926, 2468.790152837011, 2482.453860150764, 2489.166129346671, 2326.639370897514, 2419.8064154867657, 2428.3929017745563, 2442.05980697431, 2447.840890342131, 2430.5989511730545, 2458.9016439342404, 2474.6040633498733, 2477.752224777526, 2480.0327872129706, 9038.0, 9235.399699019543, 9236.72289831306, 9240.260779137652, 9243.168114869626, 5463.754131064112, 5592.636478628876, 5600.691961942091, 5600.971995140039, 5609.747561359749, 3423.5720534933134, 3529.3294274087843, 3659.2681463707363, 3710.7402481826284, 3725.9441108383394, 1207.3721664734376, 1213.3751118174148, 1217.9269243845308, 1250.593557093948, 1254.9811752823714, 12886.226826390446, 13022.153560018663, 13025.413856376486, 13029.087963842534, 13047.86952130476, 4707.529247075265, 4772.164871147535, 4783.932981213059, 4909.189983652729, 4955.702657840541, 10088.848667269996, 10107.646232381978, 10111.494425278706, 10118.342307749972, 10131.037551432602, 5571.415001424792, 5573.27547418833, 5573.526250268773, 5577.776888924398, 5581.0, 4582.633389328846, 4612.76936153191, 5057.620678449131, 5178.482151784867, 5210.062188806051, 3975.6421922027157, 3977.622125898035, 4117.773522456294, 4128.938065929014, 4131.8967025824595, 1219.5670536959428, 1249.9125061245807, 1256.830327905721, 1262.3120399382358, 1274.8852603265566, 815.2621877201143, 930.1721467893581, 946.654471118037, 952.2524818017961, 964.4599024546277, 6015.428534289235, 6195.605988652517, 6199.070139479114, 6216.502679785697, 6296.433321001132, 458.6995517935757, 464.12744041202325, 469.5797261450982, 475.4152392557105, 475.4935993167266, 4093.9385909211387, 4095.9180816383505, 4109.4246805447265, 4119.835206591702, 4124.835006599702, 11327.716807079889, 11510.0, 11624.709382265512, 11680.824787628193, 11687.88312116885, 11522.36626985507, 11542.538298468135, 11545.769084618261, 11550.364729939934, 11569.305841649528, 8112.553809540532, 8464.111268316861, 8467.915320846836, 8501.277391421674, 8611.0, 6036.0644100164545, 6069.36271691466, 6302.779402721067, 6506.902396463962, 6582.703778329891, 10387.324823886423, 10407.06336429725, 10581.947090264499, 10688.091512221548, 10720.35677859331, 10582.576696932036, 10587.258891877495, 10798.514066866852, 10891.400972946247, 10940.1794865387, 7456.291652293024, 7940.84118317642, 8098.878516822135, 8170.468919520212, 8331.750047498585, 8927.821443570961, 9121.77195570116, 9125.817483650422, 9127.680531181377, 9127.908720912706, 7865.0, 8080.0707918590115, 8081.191880812107, 8094.231048050426, 8385.161483851565, 8000.639971201426, 8002.159773223961, 8094.757157285291, 8143.470674406145, 8257.545834979133, 6607.108040414675, 6897.379235868796, 7032.0, 7161.355478007004, 7163.677634506355, 5127.743612819419, 5326.0, 5419.864503387447, 5461.508464238238, 5528.336599608073, 5322.254884316276, 5378.19327100938, 5455.8363249102595, 5473.370562385377, 5677.687727175045], "value": 4734.579826103626, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.6330493322812, 315.6275594798138, 321.8471226167571, 326.54447046370314, 327.2065241788662, 5226.634135610509, 5247.790088396469, 5261.868453288665, 5262.184361423976, 5290.761915713792, 5247.763850626721, 5254.553362964143, 5267.28891599634, 5282.31329927109, 5294.947050529494, 3938.3304838177505, 4011.959880401188, 4099.651529619986, 4126.50211269716, 4152.896177595554, 4095.815688294034, 4116.242117781079, 4132.483439570054, 4135.400366946791, 4159.500859896817, 4076.8165432555606, 4083.0, 4094.9181016379653, 4099.508059032905, 4114.8148333324925, 3931.5282166140105, 3949.4273330367223, 4092.1789803537436, 4120.484939382577, 4134.875953721393, 3610.1696609779633, 3616.258666973264, 3618.3486972344967, 3624.7825130492256, 3632.9636703633155, 5164.974175129138, 5180.974095129538, 5190.143626301656, 5199.688018718889, 5217.399999000126, 4418.0, 4643.374818813422, 4652.418447694038, 4685.8594242172785, 4755.262934245197, 5458.808941687062, 5465.180222966547, 5470.5350045246205, 5475.780968761284, 5482.506574408282, 3870.935492739491, 3931.3709806430834, 3932.58707835676, 3935.3506671399186, 3943.72393932422, 5717.714114294268, 5728.942710572883, 5740.110282906115, 5742.219911827388, 5747.856303592402, 4102.938455923104, 4107.322291821816, 4122.113745544722, 4122.855700050543, 4136.834526618902, 4103.302438585448, 4204.348326009474, 4212.178625168062, 4212.936805947913, 4213.936790948138, 4702.741349225826, 4721.834735784231, 4722.150012997625, 4736.573708366263, 4740.440162312381, 5453.672779633234, 5465.289512363447, 5486.174620070667, 5522.22688823565, 5595.804146854841, 1411.6823714664324, 1426.2227086238024, 1431.3201229416152, 1442.9927850360682, 1444.306732768277, 926.4395041000232, 936.7564433247405, 985.2905907746411, 991.4943378876825, 1004.1063453526367, 5239.895202095937, 5369.892602147936, 5387.353517577916, 5401.648892821954, 5404.405515393303, 5702.486776190163, 5716.599838011382, 5730.5129064030025, 5748.815450128925, 5769.221155144086, 5232.73836308183, 5293.638539527351, 5301.708406037628, 5325.334333208349, 5354.170103633943, 2961.2448825549345, 2995.5735853958363, 2999.2154667972522, 3001.444732724453, 3002.2044158298067, 5785.768569257267, 5807.216025836543, 5811.0, 5813.168851811666, 5823.272090988627, 5503.724813759296, 5764.654120752768, 5772.682502462405, 5792.565557583198, 5806.809604031164, 5757.913631295534, 5759.942400576108, 5780.16187652788, 5786.247787787561, 5794.449527294901, 5392.9237243662255, 5616.157576363578, 5618.101103823368, 5629.802956896653, 5634.85912852182, 5563.77744890216, 5569.554435645057, 5574.91637625444, 5585.00672019508, 5592.3848376677715, 5768.3078030634915, 5791.536677065909, 5792.855178620568, 5805.970970145291, 5813.79651712188, 3953.288408086572, 4072.674186065167, 4074.5517993020153, 4098.91802163961, 4100.528439229527, 5601.439856014366, 5634.788520468119, 5648.870225954907, 5657.207990881362, 5669.461401166883, 5511.22842802, 5513.200585914939, 5524.91712624319, 5548.44771405142, 5564.247262112422, 5218.7129707864915, 5425.593080518898, 5429.484199001088, 5433.212184233343, 5443.727813609383, 5382.165764306539, 5408.188771684278, 5425.728713564385, 5445.319335083114, 5482.205080263419, 5300.3639563252655, 5338.839834804962, 5446.591505637014, 5472.72636368188, 5492.340919089735, 5362.249285100167, 5374.758135883816, 5409.026375252335, 5409.161579955114, 5451.155070964007], "value": 4211.755225252202, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.2758465665287, 770.2567022822975, 775.298354988735, 785.5129819511903, 801.2388231180378, 16416.589585260375, 17135.742963855522, 17143.0, 17176.91411542942, 17254.0, 14363.425462981495, 15567.53297401077, 16195.757063644054, 16395.098269595168, 16913.069781162063, 797.9561124138185, 813.6582635293191, 816.3754727633366, 818.2104269380047, 819.0744458761591, 662.496502657981, 775.2983549887333, 786.5202226641744, 795.757294025322, 806.1414593457978, 856.5289090999925, 866.0, 867.3408209760596, 867.371155911965, 868.0624925080898, 862.3833958719483, 864.269692110169, 865.247234905633, 865.4980111535294, 866.2420382165606, 505.5652139160319, 599.9790007349721, 614.0727501472763, 617.5769597825504, 631.9968400158016, 15581.532554023284, 15667.764983525256, 15705.52883413488, 15710.842891571052, 15722.764158537517, 14131.929340353336, 14238.85761142396, 14574.198419086946, 14702.705945881129, 15175.468858590008, 13907.165570065828, 13918.117609634703, 13945.0, 14170.0, 14227.573172804832, 4498.110793466719, 4546.52261512543, 4551.317302404633, 4570.0, 4658.138244424792, 84582.57708711478, 85095.57452212762, 85452.0, 87839.56080219558, 88355.0, 11758.764824703629, 11809.114316426303, 11918.821217681741, 11919.165658404001, 12148.635540933787, 21638.809865457235, 21910.0, 21960.780392195873, 21967.560648787254, 22051.66922496164, 8508.361872859561, 8564.400491965627, 8695.565221739014, 8941.821163576693, 9134.223590994707, 4790.688605240648, 4815.3499277597775, 4824.782884770194, 4827.300041493963, 4990.600751939836, 3674.210044840319, 3839.3665045267776, 3841.2701586698436, 3842.1547259602844, 3842.7502212356108, 685.9451243900576, 689.8447849233883, 717.7667258141125, 726.9273072692794, 729.4893574497872, 669.8626781509779, 757.2730179028194, 779.3531368963746, 787.9251471110235, 799.9680012799423, 9961.850572241423, 10513.053825155772, 10514.421706806199, 10552.683419497427, 10599.470026498644, 751.8909608327782, 775.441681988967, 775.7789030126387, 775.8874963130314, 781.9100803407567, 4840.7337596432535, 4958.479359667255, 4985.25250109611, 5007.148784706608, 5016.623753218523, 71650.0, 91655.54172229096, 104985.17596356213, 128025.0, 133789.0, 108485.0, 109574.0, 111002.44498777455, 111441.0, 111861.0, 59042.40957590455, 61450.38549614447, 81289.59355203068, 94949.52525237329, 97107.51446242865, 8889.4666320021, 9224.723258302261, 9351.953240233755, 9355.4386736796, 9389.718308450758, 54040.729796350766, 55222.723886380314, 55458.16812747893, 56701.0, 56807.43192568187, 77327.226727732, 84179.5791021041, 84204.57897710473, 84866.0, 87067.0, 12407.773978765405, 12414.56549020816, 12517.374131293582, 12614.810777838524, 12673.93663031679, 13846.86153138496, 14266.714665706835, 14983.550493485212, 15096.39614415411, 15701.450449234222, 12781.808272875729, 13023.739525209254, 13217.34138942899, 13239.735205296032, 13648.454061837416, 13249.735005300034, 13647.38586763618, 13651.385687644279, 13782.65543361424, 14040.297985100908, 11954.19504528125, 11976.880231197576, 12414.379281036092, 12438.19151755133, 12510.935695610122, 10999.61501347449, 11148.219624626523, 11173.441327933408, 11325.15061370417, 11698.532058717556, 11090.778184436427, 11225.540679711585, 11293.774124517628, 11319.434028298718, 11541.769164616828], "value": 7813.905702939813, "unit": "score"}], "name": "dromaeo_css", "value": 7328.13330828622, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 131.0, 112.0, 116.0, 110.0, 112.0, 112.0, 110.0, 110.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 150.0, 180.0, 119.0, 143.0, 153.0, 125.0, 173.0, 121.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 163.0, 163.0, 167.0, 162.0, 163.0, 161.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 179.0, 76.0, 75.0, 75.0, 77.0, 174.0, 76.0], "value": 96.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 148.0, 84.0, 83.0, 83.0, 144.0, 84.0, 83.0, 142.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 53.0, 54.0, 52.0, 52.0, 52.0, 53.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 84.0, 79.0, 82.0, 89.0, 81.0, 79.0, 79.0, 87.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 127.0, 126.0, 127.0, 134.0, 131.0, 131.0, 158.0, 129.0], "value": 131.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 165.0, 166.0, 168.0, 169.0, 165.0, 161.0, 161.0, 164.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 65.0, 65.0, 66.0, 69.0, 65.0, 70.0, 66.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1537.3000000000002, "alertThreshold": 2.0}]} 22:34:34 INFO - Return code: 0 22:34:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:34:34 INFO - # TBPL SUCCESS # 22:34:34 INFO - Running post-action listener: _resource_record_post_action 22:34:34 INFO - [mozharness: 2016-08-31 05:34:34.324307Z] Finished run-tests step (success) 22:34:34 INFO - Running post-run listener: _resource_record_post_run 22:34:34 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 12288; Write bytes: 418246656; Read time: 284; Write time: 317616 22:34:34 INFO - TinderboxPrint: CPU usage
13.1% 22:34:34 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 284 22:34:34 INFO - TinderboxPrint: I/O write bytes / time
418,246,656 / 317,616 22:34:34 INFO - TinderboxPrint: CPU idle
8,285.4 (86.9%) 22:34:34 INFO - TinderboxPrint: CPU user
1,199.5 (12.6%) 22:34:34 INFO - TinderboxPrint: Swap in / out
0 / 0 22:34:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 129085440; Read time: 212; Write time: 97924 22:34:34 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288546816; Read time: 64; Write time: 219692 22:34:34 INFO - Running post-run listener: _upload_blobber_files 22:34:34 INFO - Blob upload gear active. 22:34:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:34:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:34:34 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:34:34 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:34:35 INFO - (blobuploader) - INFO - Open directory for files ... 22:34:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:34:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:34:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:39 INFO - (blobuploader) - INFO - Done attempting. 22:34:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:34:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:34:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:40 INFO - (blobuploader) - INFO - Done attempting. 22:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:34:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:40 INFO - (blobuploader) - INFO - Done attempting. 22:34:40 INFO - (blobuploader) - INFO - Iteration through files over. 22:34:40 INFO - Return code: 0 22:34:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:34:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:34:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50513aebd1a0a5a53f28fda9ba44ccbf4e6260a7c462d1d3079f5ed99c697179692b20f771c13439edb403948488dac557ae4e7148a4874e7674a5317adfcfd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0697cc153c7fefb24bad7e2a9ca8772d0c773d00e3fac250a6d38497f5b00b4c843a76ddfd01f2f769a52eb9ad5ff80a92c45e340a265cc81543fd1b9cf4b0f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68bd8d255806b90748fc9cadac14eb412a277555dc52e5269f650245005e3a52d03e3a02cb15f1ef9e7167424c05d5545fc51ae3c254af0fbf91a43153eb97f0"} 22:34:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:34:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:34:40 INFO - Contents: 22:34:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50513aebd1a0a5a53f28fda9ba44ccbf4e6260a7c462d1d3079f5ed99c697179692b20f771c13439edb403948488dac557ae4e7148a4874e7674a5317adfcfd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0697cc153c7fefb24bad7e2a9ca8772d0c773d00e3fac250a6d38497f5b00b4c843a76ddfd01f2f769a52eb9ad5ff80a92c45e340a265cc81543fd1b9cf4b0f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68bd8d255806b90748fc9cadac14eb412a277555dc52e5269f650245005e3a52d03e3a02cb15f1ef9e7167424c05d5545fc51ae3c254af0fbf91a43153eb97f0"} 22:34:40 INFO - Running post-run listener: copy_logs_to_upload_dir 22:34:40 INFO - Copying logs to upload dir... 22:34:40 INFO - mkdir: /builds/slave/test/build/upload/logs 22:34:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.956208 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-30 22:34:40.159473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 22:34:40.163299) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50513aebd1a0a5a53f28fda9ba44ccbf4e6260a7c462d1d3079f5ed99c697179692b20f771c13439edb403948488dac557ae4e7148a4874e7674a5317adfcfd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0697cc153c7fefb24bad7e2a9ca8772d0c773d00e3fac250a6d38497f5b00b4c843a76ddfd01f2f769a52eb9ad5ff80a92c45e340a265cc81543fd1b9cf4b0f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68bd8d255806b90748fc9cadac14eb412a277555dc52e5269f650245005e3a52d03e3a02cb15f1ef9e7167424c05d5545fc51ae3c254af0fbf91a43153eb97f0"} build_url:https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/JVN1JdDUTaWujUGR2LfM3w/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/Firefox/sha512/50513aebd1a0a5a53f28fda9ba44ccbf4e6260a7c462d1d3079f5ed99c697179692b20f771c13439edb403948488dac557ae4e7148a4874e7674a5317adfcfd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0697cc153c7fefb24bad7e2a9ca8772d0c773d00e3fac250a6d38497f5b00b4c843a76ddfd01f2f769a52eb9ad5ff80a92c45e340a265cc81543fd1b9cf4b0f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68bd8d255806b90748fc9cadac14eb412a277555dc52e5269f650245005e3a52d03e3a02cb15f1ef9e7167424c05d5545fc51ae3c254af0fbf91a43153eb97f0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 22:34:40.189138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:34:40.189483) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472614990.233922-1324045851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 22:34:40.274739) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 22:34:40.275091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 22:34:40.275414) ========= ========= Total master_lag: 0.18 =========