builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1470880791.61 results: success (0) buildid: 20160810163003 builduid: f3bdd833ff9740a580904c63b9b99137 revision: 65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.608752) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.609147) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.609449) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.632974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.633318) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 18:59:51-- 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% 77.4M=0s 2016-08-10 18:59:51 (77.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138454 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.807950) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.808255) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014510 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.859540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:51.859844) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5 --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 65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5 --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=5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 18:59:52,059 truncating revision to first 12 chars 2016-08-10 18:59:52,059 Setting DEBUG logging. 2016-08-10 18:59:52,059 attempt 1/10 2016-08-10 18:59:52,059 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65520f4cf4cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 18:59:52,363 unpacking tar archive at: mozilla-central-65520f4cf4cc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650623 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:52.523619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:52.523898) ========= script_repo_revision: 65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:52.524269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:52.526927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 18:59:52.538073) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-10 18:59:52.538346) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:52 INFO - MultiFileLogger online at 20160810 18:59:52 in /builds/slave/test 18:59:52 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 18:59:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:52 INFO - {'append_to_log': False, 18:59:52 INFO - 'base_work_dir': '/builds/slave/test', 18:59:52 INFO - 'blob_upload_branch': 'Firefox', 18:59:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:52 INFO - 'branch': 'Firefox', 18:59:52 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:52 INFO - 'config_files': ('talos/linux_config.py',), 18:59:52 INFO - 'default_actions': ('clobber', 18:59:52 INFO - 'read-buildbot-config', 18:59:52 INFO - 'download-and-extract', 18:59:52 INFO - 'populate-webroot', 18:59:52 INFO - 'create-virtualenv', 18:59:52 INFO - 'install', 18:59:52 INFO - 'run-tests'), 18:59:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:52 INFO - 'download_minidump_stackwalk': True, 18:59:52 INFO - 'download_symbols': 'ondemand', 18:59:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:59:52 INFO - 'tooltool.py': '/tools/tooltool.py', 18:59:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:59:52 INFO - '/tools/misc-python/virtualenv.py')}, 18:59:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:52 INFO - 'installer_path': 'installer.exe', 18:59:52 INFO - 'log_level': 'info', 18:59:52 INFO - 'log_name': 'talos', 18:59:52 INFO - 'log_to_console': True, 18:59:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:52 INFO - 'opt_config_files': (), 18:59:52 INFO - 'pip_index': False, 18:59:52 INFO - 'sps_profile': False, 18:59:52 INFO - 'sps_profile_interval': 0, 18:59:52 INFO - 'suite': 'dromaeojs', 18:59:52 INFO - 'system_bits': '32', 18:59:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:52 INFO - 'title': 'talos-linux64-ix-051', 18:59:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:52 INFO - 'use_talos_json': True, 18:59:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:52 INFO - 'work_dir': 'build'} 18:59:52 INFO - [mozharness: 2016-08-11 01:59:52.829636Z] Running clobber step. 18:59:52 INFO - Running pre-action listener: _resource_record_pre_action 18:59:52 INFO - Running main action method: clobber 18:59:52 INFO - rmtree: /builds/slave/test/build 18:59:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:53 INFO - Running post-action listener: _resource_record_post_action 18:59:53 INFO - [mozharness: 2016-08-11 01:59:53.287292Z] Finished clobber step (success) 18:59:53 INFO - [mozharness: 2016-08-11 01:59:53.287394Z] Running read-buildbot-config step. 18:59:53 INFO - Running pre-action listener: _resource_record_pre_action 18:59:53 INFO - Running main action method: read_buildbot_config 18:59:53 INFO - Using buildbot properties: 18:59:53 INFO - { 18:59:53 INFO - "project": "", 18:59:53 INFO - "product": "firefox", 18:59:53 INFO - "script_repo_revision": "production", 18:59:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:59:53 INFO - "repository": "", 18:59:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:59:53 INFO - "buildid": "20160810163003", 18:59:53 INFO - "pgo_build": "True", 18:59:53 INFO - "basedir": "/builds/slave/test", 18:59:53 INFO - "buildnumber": 522, 18:59:53 INFO - "slavename": "talos-linux64-ix-051", 18:59:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:59:53 INFO - "platform": "ubuntu64_hw", 18:59:53 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:59:53 INFO - "branch": "mozilla-central", 18:59:53 INFO - "repo_path": "mozilla-central", 18:59:53 INFO - "revision": "65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5", 18:59:53 INFO - "stage_platform": "linux64-pgo", 18:59:53 INFO - "builduid": "f3bdd833ff9740a580904c63b9b99137", 18:59:53 INFO - "slavebuilddir": "test" 18:59:53 INFO - } 18:59:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:59:53 INFO - Running post-action listener: _resource_record_post_action 18:59:53 INFO - [mozharness: 2016-08-11 01:59:53.289864Z] Finished read-buildbot-config step (success) 18:59:53 INFO - [mozharness: 2016-08-11 01:59:53.289953Z] Running download-and-extract step. 18:59:53 INFO - Running pre-action listener: _resource_record_pre_action 18:59:53 INFO - Running main action method: download_and_extract 18:59:53 INFO - mkdir: /builds/slave/test/build/tests 18:59:53 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')]} 18:59:53 INFO - trying https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:59:53 INFO - Downloading https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:53 INFO - Downloaded 1361 bytes. 18:59:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:59:53 INFO - Using the following test package requirements: 18:59:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:59:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:59:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'jsshell-linux-x86_64.zip'], 18:59:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:59:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:59:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:59:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:59:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:59:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:59:53 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')]} 18:59:53 INFO - trying https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:59:53 INFO - Downloading https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:54 INFO - Downloaded 21833879 bytes. 18:59:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:59:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:59:56 INFO - Return code: 0 18:59:56 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 18:59: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')]} 18:59:56 INFO - trying https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:59:56 INFO - Downloading https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:57 INFO - Downloaded 21833879 bytes. 18:59:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:59:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:59:58 INFO - Return code: 0 18:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:58 INFO - trying https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:59:58 INFO - Downloading https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 18:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 19:00:00 INFO - Downloaded 14413719 bytes. 19:00:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:00:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:00:00 INFO - Return code: 0 19:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:00 INFO - trying https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:00:00 INFO - Downloading https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 19:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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 19:00:03 INFO - Downloaded 59353073 bytes. 19:00:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:00:03 INFO - mkdir: /builds/slave/test/properties 19:00:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:03 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:03 INFO - Contents: 19:00:03 INFO - build_url:https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:00:04 INFO - Running post-action listener: _resource_record_post_action 19:00:04 INFO - Running post-action listener: set_extra_try_arguments 19:00:04 INFO - [mozharness: 2016-08-11 02:00:04.576861Z] Finished download-and-extract step (success) 19:00:04 INFO - [mozharness: 2016-08-11 02:00:04.577094Z] Running populate-webroot step. 19:00:04 INFO - Running pre-action listener: _resource_record_pre_action 19:00:04 INFO - Running main action method: populate_webroot 19:00:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:04 INFO - '--tppagecycles', 19:00:04 INFO - '7'], 19:00:04 INFO - 'tests': ['tsvgm']}}, 19:00:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['damp', 'tps']}, 19:00:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'tests': ['damp', 'tps']}, 19:00:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['dromaeo_dom']}, 19:00:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['basic_compositor_video']}, 19:00:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:00:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['a11yr', 19:00:04 INFO - 'ts_paint', 19:00:04 INFO - 'tpaint', 19:00:04 INFO - 'sessionrestore', 19:00:04 INFO - 'sessionrestore_no_auto_restore', 19:00:04 INFO - 'tabpaint']}, 19:00:04 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:04 INFO - 'ts_paint', 19:00:04 INFO - 'tpaint', 19:00:04 INFO - 'sessionrestore', 19:00:04 INFO - 'sessionrestore_no_auto_restore', 19:00:04 INFO - 'tabpaint']}, 19:00:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:04 INFO - 'ts_paint', 19:00:04 INFO - 'tpaint', 19:00:04 INFO - 'sessionrestore', 19:00:04 INFO - 'sessionrestore_no_auto_restore', 19:00:04 INFO - 'tabpaint']}, 19:00:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:04 INFO - 'ts_paint', 19:00:04 INFO - 'tpaint', 19:00:04 INFO - 'sessionrestore', 19:00:04 INFO - 'sessionrestore_no_auto_restore', 19:00:04 INFO - 'tabpaint']}, 19:00:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['a11yr', 19:00:04 INFO - 'ts_paint', 19:00:04 INFO - 'tpaint', 19:00:04 INFO - 'sessionrestore', 19:00:04 INFO - 'sessionrestore_no_auto_restore', 19:00:04 INFO - 'tabpaint']}, 19:00:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['a11yr', 19:00:04 INFO - 'ts_paint', 19:00:04 INFO - 'tpaint', 19:00:04 INFO - 'sessionrestore', 19:00:04 INFO - 'sessionrestore_no_auto_restore', 19:00:04 INFO - 'tabpaint']}, 19:00:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['tsvgx', 19:00:04 INFO - 'tsvgr_opacity', 19:00:04 INFO - 'tart', 19:00:04 INFO - 'tscrollx', 19:00:04 INFO - 'cart']}, 19:00:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:04 INFO - 'tsvgr_opacity', 19:00:04 INFO - 'tart', 19:00:04 INFO - 'tscrollx', 19:00:04 INFO - 'cart']}, 19:00:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'talos_options': ['--disable-e10s'], 19:00:04 INFO - 'tests': ['tp5o']}, 19:00:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'tests': ['tp5o']}, 19:00:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'talos_options': ['--disable-e10s', 19:00:04 INFO - '--xperf_path', 19:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:04 INFO - 'tests': ['tp5n']}, 19:00:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:04 INFO - 'talos_options': ['--xperf_path', 19:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:04 INFO - 'tests': ['tp5n']}}, 19:00:04 INFO - 'talos.zip': {'path': '', 19:00:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:04 INFO - Running post-action listener: _resource_record_post_action 19:00:04 INFO - [mozharness: 2016-08-11 02:00:04.598182Z] Finished populate-webroot step (success) 19:00:04 INFO - [mozharness: 2016-08-11 02:00:04.598272Z] Running create-virtualenv step. 19:00:04 INFO - Running pre-action listener: _resource_record_pre_action 19:00:04 INFO - Running main action method: create_virtualenv 19:00:04 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:04 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 19:00:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:04 INFO - Using real prefix '/usr' 19:00:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:00:05 INFO - Installing distribute.............................................................................................................................................................................................done. 19:00:07 INFO - Installing pip.................done. 19:00:07 INFO - Return code: 0 19:00:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:07 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:07 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 19:00:07 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 19:00:07 INFO - Using env: {'DISPLAY': ':0', 19:00:07 INFO - 'HOME': '/home/cltbld', 19:00:07 INFO - 'LANG': 'en_US.UTF-8', 19:00:07 INFO - 'LANGUAGE': 'en_US:en', 19:00:07 INFO - 'LOGNAME': 'cltbld', 19:00:07 INFO - 'MAIL': '/var/mail/cltbld', 19:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:07 INFO - 'MOZ_NO_REMOTE': '1', 19:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:07 INFO - 'NO_EM_RESTART': '1', 19:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:07 INFO - 'PWD': '/builds/slave/test', 19:00:07 INFO - 'SHELL': '/bin/bash', 19:00:07 INFO - 'SHLVL': '1', 19:00:07 INFO - 'TERM': 'linux', 19:00:07 INFO - 'TMOUT': '86400', 19:00:07 INFO - 'USER': 'cltbld', 19:00:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:07 INFO - '_': '/tools/buildbot/bin/python'} 19:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:07 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 19:00:07 INFO - Cleaning up... 19:00:07 INFO - Return code: 0 19:00:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:07 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:07 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 19:00:07 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 19:00:07 INFO - Using env: {'DISPLAY': ':0', 19:00:07 INFO - 'HOME': '/home/cltbld', 19:00:07 INFO - 'LANG': 'en_US.UTF-8', 19:00:07 INFO - 'LANGUAGE': 'en_US:en', 19:00:07 INFO - 'LOGNAME': 'cltbld', 19:00:07 INFO - 'MAIL': '/var/mail/cltbld', 19:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:07 INFO - 'MOZ_NO_REMOTE': '1', 19:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:07 INFO - 'NO_EM_RESTART': '1', 19:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:07 INFO - 'PWD': '/builds/slave/test', 19:00:07 INFO - 'SHELL': '/bin/bash', 19:00:07 INFO - 'SHLVL': '1', 19:00:07 INFO - 'TERM': 'linux', 19:00:07 INFO - 'TMOUT': '86400', 19:00:07 INFO - 'USER': 'cltbld', 19:00:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:07 INFO - '_': '/tools/buildbot/bin/python'} 19:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:07 INFO - Downloading/unpacking psutil>=3.1.1 19:00:07 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 19:00:07 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 19:00:07 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 19:00:07 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 19:00:07 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 19:00:07 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 19:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:11 INFO - Installing collected packages: psutil 19:00:11 INFO - Running setup.py install for psutil 19:00:11 INFO - building 'psutil._psutil_linux' extension 19:00:11 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 19:00:11 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 19:00:11 INFO - building 'psutil._psutil_posix' extension 19:00:11 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 19:00:11 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 19:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:11 INFO - Successfully installed psutil 19:00:11 INFO - Cleaning up... 19:00:11 INFO - Return code: 0 19:00:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:00:11 INFO - Using env: {'DISPLAY': ':0', 19:00:11 INFO - 'HOME': '/home/cltbld', 19:00:11 INFO - 'LANG': 'en_US.UTF-8', 19:00:11 INFO - 'LANGUAGE': 'en_US:en', 19:00:11 INFO - 'LOGNAME': 'cltbld', 19:00:11 INFO - 'MAIL': '/var/mail/cltbld', 19:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:11 INFO - 'MOZ_NO_REMOTE': '1', 19:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:11 INFO - 'NO_EM_RESTART': '1', 19:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:11 INFO - 'PWD': '/builds/slave/test', 19:00:11 INFO - 'SHELL': '/bin/bash', 19:00:11 INFO - 'SHLVL': '1', 19:00:11 INFO - 'TERM': 'linux', 19:00:11 INFO - 'TMOUT': '86400', 19:00:11 INFO - 'USER': 'cltbld', 19:00:11 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:11 INFO - '_': '/tools/buildbot/bin/python'} 19:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:00:15 INFO - Installing collected packages: mozsystemmonitor 19:00:15 INFO - Running setup.py install for mozsystemmonitor 19:00:15 INFO - Successfully installed mozsystemmonitor 19:00:15 INFO - Cleaning up... 19:00:15 INFO - Return code: 0 19:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:00:15 INFO - Using env: {'DISPLAY': ':0', 19:00:15 INFO - 'HOME': '/home/cltbld', 19:00:15 INFO - 'LANG': 'en_US.UTF-8', 19:00:15 INFO - 'LANGUAGE': 'en_US:en', 19:00:15 INFO - 'LOGNAME': 'cltbld', 19:00:15 INFO - 'MAIL': '/var/mail/cltbld', 19:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:15 INFO - 'MOZ_NO_REMOTE': '1', 19:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:15 INFO - 'NO_EM_RESTART': '1', 19:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:15 INFO - 'PWD': '/builds/slave/test', 19:00:15 INFO - 'SHELL': '/bin/bash', 19:00:15 INFO - 'SHLVL': '1', 19:00:15 INFO - 'TERM': 'linux', 19:00:15 INFO - 'TMOUT': '86400', 19:00:15 INFO - 'USER': 'cltbld', 19:00:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:15 INFO - '_': '/tools/buildbot/bin/python'} 19:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:16 INFO - Downloading/unpacking jsonschema==2.5.1 19:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:00:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:18 INFO - Downloading functools32-3.2.3-2.tar.gz 19:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:00:18 INFO - warning: no files found matching '*.txt' 19:00:18 INFO - no previously-included directories found matching 'build' 19:00:18 INFO - no previously-included directories found matching 'dist' 19:00:18 INFO - no previously-included directories found matching '.git*' 19:00:18 INFO - Installing collected packages: jsonschema, functools32 19:00:18 INFO - Running setup.py install for functools32 19:00:18 INFO - warning: no files found matching '*.txt' 19:00:18 INFO - no previously-included directories found matching 'build' 19:00:18 INFO - no previously-included directories found matching 'dist' 19:00:18 INFO - no previously-included directories found matching '.git*' 19:00:18 INFO - Successfully installed jsonschema functools32 19:00:18 INFO - Cleaning up... 19:00:18 INFO - Return code: 0 19:00:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:00:18 INFO - Using env: {'DISPLAY': ':0', 19:00:18 INFO - 'HOME': '/home/cltbld', 19:00:18 INFO - 'LANG': 'en_US.UTF-8', 19:00:18 INFO - 'LANGUAGE': 'en_US:en', 19:00:18 INFO - 'LOGNAME': 'cltbld', 19:00:18 INFO - 'MAIL': '/var/mail/cltbld', 19:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:18 INFO - 'MOZ_NO_REMOTE': '1', 19:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:18 INFO - 'NO_EM_RESTART': '1', 19:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:18 INFO - 'PWD': '/builds/slave/test', 19:00:18 INFO - 'SHELL': '/bin/bash', 19:00:18 INFO - 'SHLVL': '1', 19:00:18 INFO - 'TERM': 'linux', 19:00:18 INFO - 'TMOUT': '86400', 19:00:18 INFO - 'USER': 'cltbld', 19:00:18 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:18 INFO - '_': '/tools/buildbot/bin/python'} 19:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:00:19 INFO - Cleaning up... 19:00:19 INFO - Return code: 0 19:00:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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')]} 19:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:00:19 INFO - Using env: {'DISPLAY': ':0', 19:00:19 INFO - 'HOME': '/home/cltbld', 19:00:19 INFO - 'LANG': 'en_US.UTF-8', 19:00:19 INFO - 'LANGUAGE': 'en_US:en', 19:00:19 INFO - 'LOGNAME': 'cltbld', 19:00:19 INFO - 'MAIL': '/var/mail/cltbld', 19:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:19 INFO - 'MOZ_NO_REMOTE': '1', 19:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:19 INFO - 'NO_EM_RESTART': '1', 19:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:19 INFO - 'PWD': '/builds/slave/test', 19:00:19 INFO - 'SHELL': '/bin/bash', 19:00:19 INFO - 'SHLVL': '1', 19:00:19 INFO - 'TERM': 'linux', 19:00:19 INFO - 'TMOUT': '86400', 19:00:19 INFO - 'USER': 'cltbld', 19:00:19 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:19 INFO - '_': '/tools/buildbot/bin/python'} 19:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:19 INFO - Downloading/unpacking blobuploader==1.2.4 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:00:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:00:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:22 INFO - Downloading docopt-0.6.1.tar.gz 19:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:00:22 INFO - Installing collected packages: blobuploader, requests, docopt 19:00:22 INFO - Running setup.py install for blobuploader 19:00:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:00:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:00:22 INFO - Running setup.py install for requests 19:00:22 INFO - Running setup.py install for docopt 19:00:22 INFO - Successfully installed blobuploader requests docopt 19:00:22 INFO - Cleaning up... 19:00:22 INFO - Return code: 0 19:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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')]} 19:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:22 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:22 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 19:00:22 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 19:00:22 INFO - Using env: {'DISPLAY': ':0', 19:00:22 INFO - 'HOME': '/home/cltbld', 19:00:22 INFO - 'LANG': 'en_US.UTF-8', 19:00:22 INFO - 'LANGUAGE': 'en_US:en', 19:00:22 INFO - 'LOGNAME': 'cltbld', 19:00:22 INFO - 'MAIL': '/var/mail/cltbld', 19:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:22 INFO - 'MOZ_NO_REMOTE': '1', 19:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:22 INFO - 'NO_EM_RESTART': '1', 19:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:22 INFO - 'PWD': '/builds/slave/test', 19:00:22 INFO - 'SHELL': '/bin/bash', 19:00:22 INFO - 'SHLVL': '1', 19:00:22 INFO - 'TERM': 'linux', 19:00:22 INFO - 'TMOUT': '86400', 19:00:22 INFO - 'USER': 'cltbld', 19:00:22 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:22 INFO - '_': '/tools/buildbot/bin/python'} 19:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:22 INFO - Running setup.py (path:/tmp/pip-idrdFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:23 INFO - Running setup.py (path:/tmp/pip-xQpW3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:23 INFO - Running setup.py (path:/tmp/pip-lnpdoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:23 INFO - Running setup.py (path:/tmp/pip-1b7uKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:23 INFO - Running setup.py (path:/tmp/pip-Dk4L_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:23 INFO - Running setup.py (path:/tmp/pip-w2oxYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:23 INFO - Running setup.py (path:/tmp/pip-A91b47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:23 INFO - Running setup.py (path:/tmp/pip-hwCACA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:23 INFO - Running setup.py (path:/tmp/pip-gkw6YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:23 INFO - Running setup.py (path:/tmp/pip-sbaACt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:23 INFO - Running setup.py (path:/tmp/pip-TK7xVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:23 INFO - Running setup.py (path:/tmp/pip-pVbPZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:23 INFO - Running setup.py (path:/tmp/pip-Z_5RU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:23 INFO - Running setup.py (path:/tmp/pip-VMxD2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:23 INFO - Running setup.py (path:/tmp/pip-rA4kfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:24 INFO - Running setup.py (path:/tmp/pip-b2htEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:24 INFO - Running setup.py (path:/tmp/pip-Lwi3ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:24 INFO - Running setup.py install for manifestparser 19:00:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:24 INFO - Running setup.py install for mozcrash 19:00:24 INFO - Running setup.py install for mozdebug 19:00:24 INFO - Running setup.py install for mozdevice 19:00:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:24 INFO - Running setup.py install for mozfile 19:00:24 INFO - Running setup.py install for mozhttpd 19:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:24 INFO - Running setup.py install for mozinfo 19:00:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:24 INFO - Running setup.py install for mozInstall 19:00:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Running setup.py install for mozleak 19:00:25 INFO - Running setup.py install for mozlog 19:00:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Running setup.py install for moznetwork 19:00:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Running setup.py install for mozprocess 19:00:25 INFO - Running setup.py install for mozprofile 19:00:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Running setup.py install for mozrunner 19:00:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Running setup.py install for mozscreenshot 19:00:25 INFO - Running setup.py install for moztest 19:00:25 INFO - Running setup.py install for mozversion 19:00:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:25 INFO - Cleaning up... 19:00:26 INFO - Return code: 0 19:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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')]} 19:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:26 INFO - Using env: {'DISPLAY': ':0', 19:00:26 INFO - 'HOME': '/home/cltbld', 19:00:26 INFO - 'LANG': 'en_US.UTF-8', 19:00:26 INFO - 'LANGUAGE': 'en_US:en', 19:00:26 INFO - 'LOGNAME': 'cltbld', 19:00:26 INFO - 'MAIL': '/var/mail/cltbld', 19:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:26 INFO - 'MOZ_NO_REMOTE': '1', 19:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:26 INFO - 'NO_EM_RESTART': '1', 19:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:26 INFO - 'PWD': '/builds/slave/test', 19:00:26 INFO - 'SHELL': '/bin/bash', 19:00:26 INFO - 'SHLVL': '1', 19:00:26 INFO - 'TERM': 'linux', 19:00:26 INFO - 'TMOUT': '86400', 19:00:26 INFO - 'USER': 'cltbld', 19:00:26 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:26 INFO - '_': '/tools/buildbot/bin/python'} 19:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:26 INFO - Running setup.py (path:/tmp/pip-5MvjlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:26 INFO - Running setup.py (path:/tmp/pip-ZbfqC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:26 INFO - Running setup.py (path:/tmp/pip-niWMKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:26 INFO - Running setup.py (path:/tmp/pip-d3rxGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:26 INFO - Running setup.py (path:/tmp/pip-dIvPfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:26 INFO - Running setup.py (path:/tmp/pip-nM7IK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:26 INFO - Running setup.py (path:/tmp/pip-8FVXwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:26 INFO - Running setup.py (path:/tmp/pip-kregF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:26 INFO - Running setup.py (path:/tmp/pip-M0Edvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:26 INFO - Running setup.py (path:/tmp/pip-J5St8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:26 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)) 19:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:26 INFO - Running setup.py (path:/tmp/pip-O_RHpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:27 INFO - Running setup.py (path:/tmp/pip-DM3JZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:27 INFO - Running setup.py (path:/tmp/pip-Dv8Vda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:27 INFO - Running setup.py (path:/tmp/pip-AFFFUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:27 INFO - Running setup.py (path:/tmp/pip-sOfgLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:27 INFO - Running setup.py (path:/tmp/pip-dHMG5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:27 INFO - Running setup.py (path:/tmp/pip-wdADG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00: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 19:00:29 INFO - Downloading blessings-1.6.tar.gz 19:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:29 INFO - Installing collected packages: blessings 19:00:29 INFO - Running setup.py install for blessings 19:00:29 INFO - Successfully installed blessings 19:00:29 INFO - Cleaning up... 19:00:29 INFO - Return code: 0 19:00:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:29 INFO - Reading from file tmpfile_stdout 19:00:29 INFO - Current package versions: 19:00:29 INFO - argparse == 1.2.1 19:00:29 INFO - blessings == 1.6 19:00:29 INFO - blobuploader == 1.2.4 19:00:29 INFO - docopt == 0.6.1 19:00:29 INFO - functools32 == 3.2.3-2 19:00:29 INFO - jsonschema == 2.5.1 19:00:29 INFO - manifestparser == 1.1 19:00:29 INFO - mozInstall == 1.12 19:00:29 INFO - mozcrash == 0.17 19:00:29 INFO - mozdebug == 0.1 19:00:29 INFO - mozdevice == 0.48 19:00:29 INFO - mozfile == 1.2 19:00:29 INFO - mozhttpd == 0.7 19:00:29 INFO - mozinfo == 0.9 19:00:29 INFO - mozleak == 0.1 19:00:29 INFO - mozlog == 3.3 19:00:29 INFO - moznetwork == 0.27 19:00:29 INFO - mozprocess == 0.23 19:00:29 INFO - mozprofile == 0.28 19:00:29 INFO - mozrunner == 6.12 19:00:29 INFO - mozscreenshot == 0.1 19:00:29 INFO - mozsystemmonitor == 0.3 19:00:29 INFO - moztest == 0.7 19:00:29 INFO - mozversion == 1.4 19:00:29 INFO - psutil == 3.1.1 19:00:29 INFO - requests == 1.2.3 19:00:29 INFO - wsgiref == 0.1.2 19:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:29 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 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:29 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 19:00:29 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 19:00:29 INFO - Using env: {'DISPLAY': ':0', 19:00:29 INFO - 'HOME': '/home/cltbld', 19:00:29 INFO - 'LANG': 'en_US.UTF-8', 19:00:29 INFO - 'LANGUAGE': 'en_US:en', 19:00:29 INFO - 'LOGNAME': 'cltbld', 19:00:29 INFO - 'MAIL': '/var/mail/cltbld', 19:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:29 INFO - 'MOZ_NO_REMOTE': '1', 19:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:29 INFO - 'NO_EM_RESTART': '1', 19:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:29 INFO - 'PWD': '/builds/slave/test', 19:00:29 INFO - 'SHELL': '/bin/bash', 19:00:29 INFO - 'SHLVL': '1', 19:00:29 INFO - 'TERM': 'linux', 19:00:29 INFO - 'TMOUT': '86400', 19:00:29 INFO - 'USER': 'cltbld', 19:00:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:29 INFO - '_': '/tools/buildbot/bin/python'} 19:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:00:30 INFO - Cleaning up... 19:00:30 INFO - Return code: 0 19:00:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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')]} 19:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', '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 19:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:00:30 INFO - Using env: {'DISPLAY': ':0', 19:00:30 INFO - 'HOME': '/home/cltbld', 19:00:30 INFO - 'LANG': 'en_US.UTF-8', 19:00:30 INFO - 'LANGUAGE': 'en_US:en', 19:00:30 INFO - 'LOGNAME': 'cltbld', 19:00:30 INFO - 'MAIL': '/var/mail/cltbld', 19:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:30 INFO - 'MOZ_NO_REMOTE': '1', 19:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:30 INFO - 'NO_EM_RESTART': '1', 19:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:30 INFO - 'PWD': '/builds/slave/test', 19:00:30 INFO - 'SHELL': '/bin/bash', 19:00:30 INFO - 'SHLVL': '1', 19:00:30 INFO - 'TERM': 'linux', 19:00:30 INFO - 'TMOUT': '86400', 19:00:30 INFO - 'USER': 'cltbld', 19:00:30 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:30 INFO - '_': '/tools/buildbot/bin/python'} 19:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:00:30 INFO - Cleaning up... 19:00:30 INFO - Return code: 0 19:00:30 INFO - Running post-action listener: _resource_record_post_action 19:00:30 INFO - Running post-action listener: _start_resource_monitoring 19:00:30 INFO - Starting resource monitoring. 19:00:30 INFO - [mozharness: 2016-08-11 02:00:30.274367Z] Finished create-virtualenv step (success) 19:00:30 INFO - [mozharness: 2016-08-11 02:00:30.274855Z] Running install step. 19:00:30 INFO - Running pre-action listener: _resource_record_pre_action 19:00:30 INFO - Running main action method: install 19:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:30 INFO - Reading from file tmpfile_stdout 19:00:30 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:30 INFO - Reading from file tmpfile_stdout 19:00:30 INFO - Output received: 19:00:30 INFO - Usage: mozinstall [options] installer 19:00:30 INFO - Options: 19:00:30 INFO - -h, --help show this help message and exit 19:00:30 INFO - -d DEST, --destination=DEST 19:00:30 INFO - Directory to install application into. [default: 19:00:30 INFO - "/builds/slave/test"] 19:00:30 INFO - --app=APP Application being installed. [default: firefox] 19:00:30 INFO - mkdir: /builds/slave/test/build/application 19:00:30 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'] 19:00:30 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 19:00:47 INFO - Reading from file tmpfile_stdout 19:00:47 INFO - Output received: 19:00:47 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:47 INFO - Running post-action listener: _resource_record_post_action 19:00:47 INFO - [mozharness: 2016-08-11 02:00:47.488389Z] Finished install step (success) 19:00:47 INFO - [mozharness: 2016-08-11 02:00:47.488634Z] Running run-tests step. 19:00:47 INFO - Running pre-action listener: _resource_record_pre_action 19:00:47 INFO - Running main action method: run_tests 19:00:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:47 INFO - Python 2.7.3 19:00:47 INFO - Return code: 0 19:00:47 INFO - grabbing minidump binary from tooltool 19:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5b3b0>, '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 0x2d5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:00:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:00:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:00:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:47 INFO - Return code: 0 19:00:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:47 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/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 19:00:47 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/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 19:00:47 INFO - Using env: {'DISPLAY': ':0', 19:00:47 INFO - 'HOME': '/home/cltbld', 19:00:47 INFO - 'LANG': 'en_US.UTF-8', 19:00:47 INFO - 'LANGUAGE': 'en_US:en', 19:00:47 INFO - 'LOGNAME': 'cltbld', 19:00:47 INFO - 'MAIL': '/var/mail/cltbld', 19:00:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:47 INFO - 'MOZ_NO_REMOTE': '1', 19:00:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:47 INFO - 'NO_EM_RESTART': '1', 19:00:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:47 INFO - 'PWD': '/builds/slave/test', 19:00:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:47 INFO - 'SHELL': '/bin/bash', 19:00:47 INFO - 'SHLVL': '1', 19:00:47 INFO - 'TERM': 'linux', 19:00:47 INFO - 'TMOUT': '86400', 19:00:47 INFO - 'USER': 'cltbld', 19:00:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688', 19:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:47 INFO - '_': '/tools/buildbot/bin/python'} 19:00:47 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/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 19:00:47 INFO - mozversion application_buildid: 20160810163003 19:00:47 INFO - mozversion application_changeset: 65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5 19:00:47 INFO - mozversion application_display_name: Nightly 19:00:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:47 INFO - mozversion application_name: Firefox 19:00:47 INFO - mozversion application_remotingname: firefox 19:00:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:00:47 INFO - mozversion application_vendor: Mozilla 19:00:47 INFO - mozversion application_version: 51.0a1 19:00:47 INFO - mozversion platform_buildid: 20160810163003 19:00:47 INFO - mozversion platform_changeset: 65520f4cf4cc57b29aea6d1a0d608d2d30ecb8f5 19:00:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:00:47 INFO - mozversion platform_version: 51.0a1 19:00:47 INFO - using testdate: 1470880847 19:00:47 INFO - actual date: 1470880847 19:00:47 INFO - starting webserver on 'localhost:36914' 19:00:47 INFO - SUITE-START | Running 2 tests 19:00:47 INFO - TEST-START | dromaeo_css 19:00:47 INFO - operating with platform_type : linux_ 19:00:47 INFO - Initialising browser for dromaeo_css test... 19:00:47 INFO - TEST-INFO | started process 5956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4MeRZF/profile http://localhost:36914/getInfo.html) 19:00:51 INFO - PROCESS | 5956 | __metrics Screen width/height:1600/1200 19:00:51 INFO - PROCESS | 5956 | colorDepth:24 19:00:51 INFO - PROCESS | 5956 | Browser inner width/height: 1024/697 19:00:51 INFO - PROCESS | 5956 | __metrics 19:00:52 INFO - PROCESS | 5956 | 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" 19:00:52 INFO - PROCESS | 5956 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:00:53 INFO - TEST-INFO | 5956: exit 0 19:00:53 INFO - Browser initialized. 19:00:53 INFO - Running cycle 1/1 for dromaeo_css test... 19:00:53 INFO - TEST-INFO | started process 6038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4MeRZF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:05 INFO - PROCESS | 6038 | RSS: Main: 155115520 19:01:05 INFO - PROCESS | 6038 | 19:01:57 INFO - PROCESS | 6038 | 1470880917043 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] 19:04:06 INFO - PROCESS | 6038 | Cycle 1(1): loaded http://localhost:36914/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36914/tests/dromaeo/cssquery-ext.html) 19:04:07 INFO - PROCESS | 6038 | RSS: Main: 237314048 19:04:07 INFO - PROCESS | 6038 | 19:07:10 INFO - PROCESS | 6038 | Cycle 1(1): loaded http://localhost:36914/tests/dromaeo/cssquery-ext.html (next: http://localhost:36914/tests/dromaeo/cssquery-jquery.html) 19:07:10 INFO - PROCESS | 6038 | RSS: Main: 251211776 19:07:10 INFO - PROCESS | 6038 | 19:10:12 INFO - PROCESS | 6038 | Cycle 1(1): loaded http://localhost:36914/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36914/tests/dromaeo/cssquery-mootools.html) 19:10:13 INFO - PROCESS | 6038 | RSS: Main: 211443712 19:10:13 INFO - PROCESS | 6038 | 19:13:15 INFO - PROCESS | 6038 | Cycle 1(1): loaded http://localhost:36914/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36914/tests/dromaeo/cssquery-prototype.html) 19:13:16 INFO - PROCESS | 6038 | RSS: Main: 211283968 19:13:16 INFO - PROCESS | 6038 | 19:16:18 INFO - PROCESS | 6038 | Cycle 1(1): loaded http://localhost:36914/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36914/tests/dromaeo/cssquery-yui.html) 19:16:19 INFO - PROCESS | 6038 | RSS: Main: 183238656 19:16:19 INFO - PROCESS | 6038 | 19:19:21 INFO - PROCESS | 6038 | Cycle 1(1): loaded http://localhost:36914/tests/dromaeo/cssquery-yui.html (next: http://localhost:36914/tests/dromaeo/cssquery-dojo.html) 19:19:22 INFO - PROCESS | 6038 | RSS: Main: 274407424 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | __start_tp_report 19:19:22 INFO - PROCESS | 6038 | _x_x_mozilla_page_load 19:19:22 INFO - PROCESS | 6038 | _x_x_mozilla_page_load_details 19:19:22 INFO - PROCESS | 6038 | |i|pagename|runs| 19:19:22 INFO - PROCESS | 6038 | |0;dojo.html;3083.0750774767566;3092.5515800208955;3122.4379611669897;3123.1099136746034;3137.043201823443;5330.3603567571845;5333.413324534303;5378.408375078739;5420.972895135524;5452.0458919689;5452.91820622691;5460.071787796074;5497.780088796443;5515;5518.392976772562;4922.655414121004;4970.6520543561965;5021.071101846152;5022.723750193738;5116.488351164872;5011.1481048221885;5055.368078990126;5056.823011194609;5070.492950704918;5077.619178561604;4887.315775791392;4890.217565189588;4969.8012079516775;4971.577415919651;5042.647014708972;4881.316615673773;4893.902121957576;4903.141950158708;4915.901681966377;4980.703269709892;4917.385326834145;4966.850994470172;4981.750912454399;4994.650374473752;5011.198208286657;5366.221897824833;5404.270423492818;5406.864828379283;5448.4279150689;5453.536449401806;5815.592908496407;5822.446867547576;5852.531797456193;5865.736041878125;5872.677002764846;5898.174255604219;5911.29064512257;5914.408559144094;5921.259842519685;5921.674307913063;5340.465953404668;5394.838154855361;5442.074847275893;5442.673439593637;5543.805966791224;6030.33666296707;6031.74287870956;6046.91123813687;6088.025915853442;6098.237720284965;5782.190493330937;5799.33307669615;5825.446582574648;5851.444112809277;5852.063669812809;5625.46558076982;5681.34664513578;5774.047282198474;5775.220345253421;5875.500582450454;5759.85600359986;5802.506786923159;5805.332386775575;5809.767609295665;5823.79616713413;5766.135079737989;5805.825825225165;5815.302163740378;5824.621399609012;5839.153322768175;4394.318880573516;4421.801018954155;4427.424434823452;4468.843590474317;4474.530174331649;4005.1989602079702;4022.054817117975;4023.7786921719585;4025.476688030596;4027.1140349123148;5402.243685883979;5425.810096646598;5439.265699130646;5517.641353312022;5539.639923404966;5828.067509198508;5833.300003999547;5837.970810145922;5842.678652674143;5844.1233814927255;5444.727763611882;5474.206240095164;5500;5512.91730624041;5513.61404701667;4909.435414927258;4957.7769000395065;4994.350734404504;4996.400431948116;5064.873378165502;6080.422359875804;6082.817515474543;6084.178635884187;6086.178365920633;6087.96956015226;5821.970890145522;5822.737976791139;5841.737121829613;5864.648121112747;5877.559183061202;5919.763209471573;6008.279006519071;6047.576669633084;6059.939400605937;6114.631938157225;5754.136879467946;5757.193992841089;5762.913556296575;5768.048272035067;5821.417858214145;5843.1235314703135;5861.560382971379;5861.7655293787775;5872.354041055395;5872.618279811799;5590.077637189818;5686.545076393962;5686.943130568641;5714.971425143014;5788.884222315614;5665.405132461032;5707.971460142673;5708.400617935057;5709.857253568528;5718.999175144416;5722.828315150552;5727.656340619576;5731.426857314235;5738.196652468741;5741.110127930177;5605.271314729059;5721.256236689203;5758.971205143947;5775.537956963349;5856.297244330707;5659.745311460912;5683.744231509675;5685.061964775931;5690.487856092972;5691.288588926384 19:19:22 INFO - PROCESS | 6038 | |1;ext.html;577.356247783721;584.5937073733754;608.4493533352318;611.1688104178317;620.0667994668024;33297;44206.33690494645;45164.322535162;45221.54778452215;45222.09555808882;45762.771186144026;46333.53666463334;46346.536534634644;46374;46374.536254637445;8642.43824151428;8755.430896991675;8766.123387661246;8771.254443372289;8777.780555486132;8970.86543701845;9075.909240907606;9083.909160908372;9085.13691199335;9087.136722011432;9130.726078217664;9159.358844880828;9171.954140229289;9180.403273787217;9196.218321442686;9061.13919177677;9102.13529714676;9109.134632209929;9111.13444222801;9113;8960.775980600472;9048.954755226248;9062.909370906273;9072.183503484652;9072.591733372014;43115.56884431147;43379.78310108429;43504.347434788506;43510.564894351286;43554.564454355685;3573.065046319349;3745.2696724138805;3826.521684789401;3860.613938606145;4039.5290369153236;32643.836780815946;33514.6648533514;33913;33930.49104263438;34014;4387.780610969471;4480.059187570614;4508.887277818081;4583.747893865836;4669.276262179367;365877;365945;366717;366829;367898;57134.71432642893;57258.427415726146;57619.25474844122;57638.42361576414;57686;60401.39598604046;60446.46651501155;60503;60519.39480605138;60545.39454605486;16894.23975921062;17002.659946801243;17031.063291518836;17119.65760684779;17186.226619801888;13212.141210821457;13387.866121338662;13399.263040532669;13430.597082087554;13432.328383580782;14487.78268325976;15755.212239387985;15775;15921.840781592267;15988.76016859748;6425.554250293647;6434.646094464756;6435.195600549931;6558.508111891627;6580.473562115019;1006.9496525173859;1045.9947700261603;1046.989654982945;1074.6114350188057;1167.3521195736448;29932.551011734842;30192.84903575512;30591.541126883116;30941.381172376878;31037.68962310393;1105.690406686129;1121.3440137519642;1128.90968722502;1152.8674202466657;1170.4908364861312;16057.598560035858;16262.430814921421;16267.51197464078;16543;16649.667006659798;510484;514539.9822012423;516037;517386;518659;60301.69849150726;60460;60533;60534;60557.39442605517;1233.4018001269378;1338.070041321292;1370.136813807295;1398.3497673581833;1415.8088658031033;28742.568861466676;31473.8426307867;31581.210469738442;31712.524312135796;32177.839110804296;49637.50362496329;50632.24051639155;50767.23849142192;50812;50968.49031509786;404829;407038;407278;407772;408504;28545.714542854304;42277;42430.15139697127;42554;42737.358939616555;47439;49346.75326623344;49993.50006499889;50029;50313;41263.793681031406;42249;42925.57074429341;43039.354409683256;44099;41382.172356552095;41802.79098604609;42581.36127958021;43273.3508997359;43349.56650433455;40216.59783402128;40665.39001915033;41516.58483415248;41767.164656707304;41789.37315940324;35794.463083054296;37338.31977560962;38148.237035258586;38279.808600956814;38732;25473.23580292594;25512.744872551037;25569.74430255748;25810.225693229233;26129.60805587956 19:19:22 INFO - PROCESS | 6038 | |2;jquery.html;370.7997681252124;380.52244433236285;390.3033085941594;395.9821808018639;416.7020580285097;16968.151592420378;19647.24938508608;19669.508262293413;19908.70136947945;21158.365249042516;19119.23523059076;19312.420627381165;19933.601327973432;19986.40040798775;20083.59832803343;5256.474352564751;5832.7958521451865;5971.611845230068;6008.909866352008;6022.457978781908;4283.271843786564;5856.033754430514;6295.653739044351;6653.567518111331;6673.699683514254;6699.229588597325;6769.830754231134;6772.762953296636;6774.085498457719;6787.864242715143;6576.177977752781;6693.732250709966;6706.329367063304;6720.596764194164;6747.08914296571;5866.354700982888;5868.970655146697;5910.704464776744;5914.704264786744;5915.822525324247;23599.88200058989;23763.524729505312;24795.380115497075;24848.503029939482;24981.125660601974;2324.2097686786574;2402.4114092047384;2469.876506174702;2500.0124950644577;2504.2362079565723;14554.908381871403;15175.696486070217;15268.923655381763;15476.690466190727;15796.763048554281;1927.2098439639822;1994.9401517954484;1995.5809280051208;2011.6580181369202;2037.9184832606677;82629.17370826275;84798.15201847964;85175.57412212962;87649.56175219083;88696.556517217;10583.153347732163;10666.78666426667;10842.644669416322;10887.673369798918;11259.549618015188;15505.612359691098;15508.302126404338;15509.379624815107;15526.146061966474;15531.378744850079;8056.234657707509;8073.798155046171;8297.709580164665;8311.127331630212;8333.583320833934;7777.300042996158;7939.325157361689;7950.165232650604;8174.509529428254;8197.590120493951;6724.562903411263;6729.528932974743;6808.421284190899;6878.105846239957;6896.310368963063;1768.938087166943;1828.838225798451;1914.808519148074;1995.9800401995794;1997.1412292713983;894.7136916186757;968.864358989742;974.2546951582045;999.5452069308448;1034.3380236648552;17034.48896533106;18638.813611863978;19062.904685476762;19197.520061998282;19460.805391945898;971.4074414607084;1001.4742260313394;1003.4180175498193;1004.3672486333563;1067.8985496377832;7918.920810791934;11017.283876548;11132.220744547803;11143.164262680331;11155.386453745123;515580;531063;534403;535517;536036;91168;92941.53529232446;93134.53432732793;93362.53318733363;93820.53089734507;1018.8573599696049;1062.1555863088863;1085.8262677971486;1088.0751361342795;1094.9288296260718;16366.509004729876;16418.425355112515;17165.399211027554;17667.38164164248;17841.46475605734;41605.58394416017;42212.78893605512;42864;43053.56946430621;43069.35395969126;59805.40194597998;59823.700881495315;60144.39855601563;60308.396916032034;61723;7812.765617031499;8278.586070696321;8726.869096963424;8742.125787421208;8906.777330566787;13748.93125534406;13796;13875.236861972688;14043.929780351033;14603.123812571279;11800.17398782077;11894.940525297317;12190.146689731633;12238.694032649255;12295.631131066082;12029.338386388483;12083.214591051554;12088.939555302168;12276.693082673004;12367.196132251374;11843.881561184278;12129;12137.878621214028;12198.81701774492;12549.623511294676;7707.730229441942;7851.2148785121035;7930.563818990011;7934.444588878723;8004.5597492138495;9984.201263899016;11639.825402618799;11655.18413711032;12327.9383603085;12685.302308372758 19:19:22 INFO - PROCESS | 6038 | |3;mootools.html;1348.8920886329095;1482.9777553336696;1522.5660686704289;1643.4740882917463;1800.4508624869409;8429.325653947684;8672.956635216822;8820.60307286172;8980.506072166045;9042.050584688612;8922.06318336575;8924.375293729441;8924.687635932743;8930.240929520998;8952.731418057452;2413.54142712884;2443.547943630434;2445.960466801611;2446.1193970170725;2446.156076153724;2404.7955923746504;2445.0097710427303;2450.779429851313;2452.3869032741813;2460.8031357491445;2374.620060790274;2375.061850569024;2402.903883844644;2404.927852164438;2407.987960060197;2330.860148391085;2339.4268404240984;2358.1157066100213;2361.9409514762183;2362.01976179886;2321.97678023221;2335.708036495438;2336.2991102669303;2336.556054349685;2347.3896786835376;8803.64785408583;8834.204921557026;8872.911270887338;8880.689175878877;8884.156005179497;5115.667481613683;5282.128448805943;5299.3905700844125;5305.973470132663;5309.097453432925;3490.947635785465;3510.8420121094614;3548.7515873888838;3591.856325746967;3594.622564630702;1197.8502687164105;1209.7217639942774;1213.7451135261606;1234.6172686467223;2052.3842847145947;12181.269123852597;12411.813822792665;12440.875591244063;12456.937715311456;12465.439055242536;4249.808758605808;4556;4611.930821037688;4617.376654151713;4625.791839367236;9678.080582344666;9700.854487182698;9721.951390243003;9746.512674366395;9757.316987810927;5279.551238144801;5284.656497327662;5286.497782710637;5291.3385826771655;5299.788008479618;4291.678124140702;4769.856904292877;4924.482929292444;4938.259261110862;4951.950480495221;3825.464434979105;3955.861544845974;3980.9203815924097;3993.101552150801;4087.795610219477;1206.5113628980278;1213.83006379107;1215.3194211241732;1217.6712287682276;1244.9564265250672;895.923846473044;926.5969303352928;932.5616960028775;968.2496065549255;991.4992928571025;5657.4908258256955;5927.614705044159;5974.970125149346;6094.756209751649;6177.537131786868;465.1952122827527;467.40872795913197;471.9457262414866;473.2121018504221;480.97595120244546;4090.365993271048;4092.1201941582704;4110.321796903538;4123.175364927027;4144.730592511477;10510.001549852752;10791.83812242817;10808.675739727822;10809;10815.459227038617;10693.572257109785;10704.571817127384;10734.033936945712;10740.624078157227;10806.675799726021;8036.959815201004;8044.477108987897;8137.104918458965;8149.551774652451;8298.626561804855;6132.359753151537;6210.596311239755;6258.405451482102;6311.684415779285;6350.206224221973;10254.897451025692;10271.845922311022;10286.742831429274;10299.227557933335;10422.947885260524;10378.948105259426;10541.104006159563;10553.894461055292;10582.682519524427;10678.946605266923;7120.430365570845;7337.046183996046;7360.815979600553;8135.837283254184;8210.3021243195;7571.848563028599;7966.322862556747;8663.826723465369;8782.121787821166;8837;7736.2650548197835;7779.299863012356;7791.532508049535;7840.960795195988;8024.638891249791;7563.092428908566;7611.505252158592;7611.543307401574;7615.7334493292465;7618.238176182337;7020.719171233093;7062;7123.572585644878;7127.8574428512175;7138.036365090854;5044.344235249394;5062.114130027304;5158.123119069842;5183.222516622386;5207.1147904857025;5062.746862656779;5121.206213036985;5151.665141765773;5263.131583288715;5284.735763211747 19:19:22 INFO - PROCESS | 6038 | |4;prototype.html;307.69230769230785;319.32940824269036;324.8408279942828;325.66456549753764;327.6903326356593;5237.423883372826;5253.973730131349;5279.815206467774;5280.9207861882105;5297.390800057994;4957.652964292501;5015.222640490721;5163.380394352683;5166.819161329354;5241.9213711794355;4125.504939407276;4135.1936372407245;4140.089180380319;4145.606167414106;4151.045259590281;4135.813888375015;4142.130152667944;4148.232576973254;4151.169766046788;4153.792310384484;3882.514685664292;3964.275540140034;4079.5920407959266;4101.425800387948;4136.627703506684;4098.200850834089;4102.056526998792;4107.54816970133;4121.216968775923;4127.649149822269;3654.6162652921594;3655.976326628548;3657.65252301031;3671.3382797471886;3672.485851980725;4983.825566105169;5006.499350065001;5029.949700503022;5116.923246151311;5143.434222235551;4657.720536767804;4658.790354434024;4669.276262179367;4680.64895132863;4701.0127873146685;5386.326709161355;5403.891922161574;5421.213923981;5448.4006759256845;5473.452654734535;3730.608286129944;3927.293087244295;3995.720299579031;4009.4386785850006;4025.335819589764;5635.690037047961;5661.490465858052;5674.035413979634;5676.432356764331;5688.4311568843195;4002.699797515198;4021.235965166609;4036.8385264589674;4044.8988775280263;4065.573114822901;3910.9413358799643;4122.896927576775;4128.566500517462;4132.628063474242;4132.834686612562;4643.349931009594;4651.581357677757;4666.8133274668635;4684.906301873943;4697.741624210701;5472.315960504937;5506.421825708323;5511.4213007633625;5514.862128446821;5532.474414930575;1339.2366351179928;1389.9297540893456;1395.3651088754593;1402.6283034995733;1414.7312010718138;886.7029545102334;898.8247291778172;905.813384466338;907.419251678926;912.4707669551644;4690.695104818176;4708.835190768306;4720.881977950578;4730.432348118248;4739.66822322441;4952.430470495867;4959.82640607577;4967.975160124249;4979.825706100269;5000.67495612784;4277.422547956048;4659.2312268476;4663.48701642819;4678.345031695565;4687.1328804171335;2581.7934565234737;2599.271484462849;2622.9639292479364;2633.5391306521283;2638.379980704533;4951.603871690256;5078.949210507848;5126.769295381717;5137.794488220504;5184.870378240499;4999.300517616937;5034.244863270539;5036.445990940993;5037.858909963251;5108.233764935215;5118.667286626357;5122.846314610568;5126.128558145124;5132.435432102466;5144.945286216242;4920.6555541111775;4928.890999525078;4932.654714169974;4937.259411088366;5162.277281180563;5559;5579.888402231852;5586.245856809279;5592.636478628876;5594.580406469612;5855.590108692521;5866.882662346815;5873.295204575478;5926.377730338252;5941.197938278277;3876.5348158221195;3952.189801090712;3980.5024371953505;4011.578784227731;4031.254217969685;5354.892902142014;5554.694491802989;5608.46719561641;5610.943890561042;5627.564970932386;5523.917141242965;5528.281323427928;5553.861763221108;5561.777528898961;5578.219049333015;5286.735663216747;5502.449755024626;5511.338639363302;5527.198556209408;5533.253010843485;5508.862278443071;5509.944900551103;5515.4760297771645;5520.392756796668;5534.363548192009;5516.172574113915;5529.640573362719;5564.415736347625;5610.663360198238;5624.578156638187;5191.402988656202;5415.864603384789;5439.782408703766;5487.835364939058;5496.450354964471 19:19:22 INFO - PROCESS | 6038 | |5;yui.html;772.9033870766154;788.9408294377921;798.3373799746212;803.8593246181921;839.0602525171809;15681.29434175462;17343.73984390232;17345.913270433644;17367.2184751686;17367.218475168615;15323.770143447857;15600.375984960616;15616.609584760386;15634.609134771636;15777.368905243777;661.7154623511888;778.404520541786;790.063774427304;804.4328748232482;824.0358780227149;792.7106606088765;803.028335713787;803.7227156630953;804.0873608454417;804.9436539442241;864.1703964194381;864.6152462154344;866.3805379153904;866.4974314897376;867.1458613265928;692.1590267824618;828.457360428914;848.9830203395898;861.0829466618031;863.9481631102122;626.7023163997111;627.0343670747044;627.5356236385043;627.6077451592755;627.77086363477;13689.247091409969;13696;13703.520376786764;13707.45170193191;13731.107478013897;13386.799198012037;13758.243296618683;13779.862201377959;14031.298435078204;14160.150390976574;13690.315484225748;13806.723865522634;13876.514321998782;14095.78856317156;14652.120872747562;4434.778261086933;4489.595936365743;4496.955030449719;4575.679702420832;4726.574608285271;79054;79471.602641987;80146;84210.15789842085;85480;10961.780764384668;10965.287256328313;11016.559337626566;11090.334579925231;11323.716907077389;19560.902195489216;19603.607927841364;19681.803181968284;19714.309999149962;19765.308214212433;8012.3590112790835;8030.317423018992;8183.71357002502;8229.835403291901;8290.751277461686;4662.790174442158;4663.137319595886;4722.504137065559;4854.708717476892;4883.682560633548;3381.5434916286476;3507.5264839246884;3508.649135086471;3510.3856825055514;3516.1209697575605;580.5268706004592;591.1457943271931;592.552622769806;593.104412337365;594.1147689942042;581.5376775463517;591.1398914579277;655.5607742812327;701.6877489517218;721;8888.466691998372;9564.56959436827;10995.835062474069;11014.724631884106;11149.275297105662;676.6988690032888;779.8986131802943;786.3905473258156;787.8857565652949;814.9918500814916;4926.211806111005;5001.324821149171;5028.220625803006;5040.243963405518;5222.451642577551;73242.26757732354;75075.62462187654;108340.91659083308;118749.40625296818;136087;63132.68433657802;98189;110718;111131;112717;90539.54730226439;91784;91831;93408;95435.52282238683;9644.95177524108;9668.564914579001;9749.658761943581;9758;9758.268129890144;51948.480515194366;52368.47631523636;53150.734246328524;53780;54464.45535544594;68267;69926.55527799913;76389;83628;84747.5762621183;13786.310684465536;14149.787753183504;14168.149911005372;14293.57119286423;14327.355269013127;15135.243237838284;15551.611209719847;15716;16000;16395.83604163991;14147;14462.927685361507;14632;14868.62828429298;15386;14426.495072672406;14757.852421476076;14935.701285974437;14997.940252430768;15048.699026019638;14015.789763153765;14274.357653905392;14495.347709352893;14829.184394858386;14840.18378989166;11672.241304315192;11727.47226374798;11986.016479163254;12015.977420175135;12225;10163.339382940085;10561.841572376266;10689.9465502672;11919.82120268214;12485.687857803265 19:19:22 INFO - PROCESS | 6038 | __end_tp_report 19:19:22 INFO - PROCESS | 6038 | __start_cc_report 19:19:22 INFO - PROCESS | 6038 | _x_x_mozilla_cycle_collect,1032 19:19:22 INFO - PROCESS | 6038 | __end_cc_report 19:19:22 INFO - PROCESS | 6038 | __startTimestamp1470881962154__endTimestamp 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | ------- Summary: start ------- 19:19:22 INFO - PROCESS | 6038 | Number of tests: 6 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | [#0] dojo.html Cycles:165 Average:5427.83 Median:5685.06 stddev:624.51 (11.0%) stddev-sans-first:598.72 19:19:22 INFO - PROCESS | 6038 | Values: 3083.1 3092.6 3122.4 3123.1 3137.0 5330.4 5333.4 5378.4 5421.0 5452.0 5452.9 5460.1 5497.8 5515.0 5518.4 4922.7 4970.7 5021.1 5022.7 5116.5 5011.1 5055.4 5056.8 5070.5 5077.6 4887.3 4890.2 4969.8 4971.6 5042.6 4881.3 4893.9 4903.1 4915.9 4980.7 4917.4 4966.9 4981.8 4994.7 5011.2 5366.2 5404.3 5406.9 5448.4 5453.5 5815.6 5822.4 5852.5 5865.7 5872.7 5898.2 5911.3 5914.4 5921.3 5921.7 5340.5 5394.8 5442.1 5442.7 5543.8 6030.3 6031.7 6046.9 6088.0 6098.2 5782.2 5799.3 5825.4 5851.4 5852.1 5625.5 5681.3 5774.0 5775.2 5875.5 5759.9 5802.5 5805.3 5809.8 5823.8 5766.1 5805.8 5815.3 5824.6 5839.2 4394.3 4421.8 4427.4 4468.8 4474.5 4005.2 4022.1 4023.8 4025.5 4027.1 5402.2 5425.8 5439.3 5517.6 5539.6 5828.1 5833.3 5838.0 5842.7 5844.1 5444.7 5474.2 5500.0 5512.9 5513.6 4909.4 4957.8 4994.4 4996.4 5064.9 6080.4 6082.8 6084.2 6086.2 6088.0 5822.0 5822.7 5841.7 5864.6 5877.6 5919.8 6008.3 6047.6 6059.9 6114.6 5754.1 5757.2 5762.9 5768.0 5821.4 5843.1 5861.6 5861.8 5872.4 5872.6 5590.1 5686.5 5686.9 5715.0 5788.9 5665.4 5708.0 5708.4 5709.9 5719.0 5722.8 5727.7 5731.4 5738.2 5741.1 5605.3 5721.3 5759.0 5775.5 5856.3 5659.7 5683.7 5685.1 5690.5 5691.3 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | [#1] ext.html Cycles:180 Average:59750.85 Median:42831.46 stddev:114858.82 (268.2%) stddev-sans-first:115095.10 19:19:22 INFO - PROCESS | 6038 | Values: 577.4 584.6 608.4 611.2 620.1 33297.0 44206.3 45164.3 45221.5 45222.1 45762.8 46333.5 46346.5 46374.0 46374.5 8642.4 8755.4 8766.1 8771.3 8777.8 8970.9 9075.9 9083.9 9085.1 9087.1 9130.7 9159.4 9172.0 9180.4 9196.2 9061.1 9102.1 9109.1 9111.1 9113.0 8960.8 9049.0 9062.9 9072.2 9072.6 43115.6 43379.8 43504.3 43510.6 43554.6 3573.1 3745.3 3826.5 3860.6 4039.5 32643.8 33514.7 33913.0 33930.5 34014.0 4387.8 4480.1 4508.9 4583.7 4669.3 365877.0 365945.0 366717.0 366829.0 367898.0 57134.7 57258.4 57619.3 57638.4 57686.0 60401.4 60446.5 60503.0 60519.4 60545.4 16894.2 17002.7 17031.1 17119.7 17186.2 13212.1 13387.9 13399.3 13430.6 13432.3 14487.8 15755.2 15775.0 15921.8 15988.8 6425.6 6434.6 6435.2 6558.5 6580.5 1006.9 1046.0 1047.0 1074.6 1167.4 29932.6 30192.8 30591.5 30941.4 31037.7 1105.7 1121.3 1128.9 1152.9 1170.5 16057.6 16262.4 16267.5 16543.0 16649.7 510484.0 514540.0 516037.0 517386.0 518659.0 60301.7 60460.0 60533.0 60534.0 60557.4 1233.4 1338.1 1370.1 1398.3 1415.8 28742.6 31473.8 31581.2 31712.5 32177.8 49637.5 50632.2 50767.2 50812.0 50968.5 404829.0 407038.0 407278.0 407772.0 408504.0 28545.7 42277.0 42430.2 42554.0 42737.4 47439.0 49346.8 49993.5 50029.0 50313.0 41263.8 42249.0 42925.6 43039.4 44099.0 41382.2 41802.8 42581.4 43273.4 43349.6 40216.6 40665.4 41516.6 41767.2 41789.4 35794.5 37338.3 38148.2 38279.8 38732.0 25473.2 25512.7 25569.7 25810.2 26129.6 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | [#2] jquery.html Cycles:180 Average:30720.44 Median:13740.57 stddev:87324.19 (635.5%) stddev-sans-first:87539.42 19:19:22 INFO - PROCESS | 6038 | Values: 370.8 380.5 390.3 396.0 416.7 16968.2 19647.2 19669.5 19908.7 21158.4 19119.2 19312.4 19933.6 19986.4 20083.6 5256.5 5832.8 5971.6 6008.9 6022.5 4283.3 5856.0 6295.7 6653.6 6673.7 6699.2 6769.8 6772.8 6774.1 6787.9 6576.2 6693.7 6706.3 6720.6 6747.1 5866.4 5869.0 5910.7 5914.7 5915.8 23599.9 23763.5 24795.4 24848.5 24981.1 2324.2 2402.4 2469.9 2500.0 2504.2 14554.9 15175.7 15268.9 15476.7 15796.8 1927.2 1994.9 1995.6 2011.7 2037.9 82629.2 84798.2 85175.6 87649.6 88696.6 10583.2 10666.8 10842.6 10887.7 11259.5 15505.6 15508.3 15509.4 15526.1 15531.4 8056.2 8073.8 8297.7 8311.1 8333.6 7777.3 7939.3 7950.2 8174.5 8197.6 6724.6 6729.5 6808.4 6878.1 6896.3 1768.9 1828.8 1914.8 1996.0 1997.1 894.7 968.9 974.3 999.5 1034.3 17034.5 18638.8 19062.9 19197.5 19460.8 971.4 1001.5 1003.4 1004.4 1067.9 7918.9 11017.3 11132.2 11143.2 11155.4 515580.0 531063.0 534403.0 535517.0 536036.0 91168.0 92941.5 93134.5 93362.5 93820.5 1018.9 1062.2 1085.8 1088.1 1094.9 16366.5 16418.4 17165.4 17667.4 17841.5 41605.6 42212.8 42864.0 43053.6 43069.4 59805.4 59823.7 60144.4 60308.4 61723.0 7812.8 8278.6 8726.9 8742.1 8906.8 13748.9 13796.0 13875.2 14043.9 14603.1 11800.2 11894.9 12190.1 12238.7 12295.6 12029.3 12083.2 12088.9 12276.7 12367.2 11843.9 12129.0 12137.9 12198.8 12549.6 7707.7 7851.2 7930.6 7934.4 8004.6 9984.2 11639.8 11655.2 12327.9 12685.3 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | [#3] mootools.html Cycles:180 Average:5812.16 Median:2625.42 stddev:3291.96 (125.4%) stddev-sans-first:3284.10 19:19:22 INFO - PROCESS | 6038 | Values: 1348.9 1483.0 1522.6 1643.5 1800.5 8429.3 8673.0 8820.6 8980.5 9042.1 8922.1 8924.4 8924.7 8930.2 8952.7 2413.5 2443.5 2446.0 2446.1 2446.2 2404.8 2445.0 2450.8 2452.4 2460.8 2374.6 2375.1 2402.9 2404.9 2408.0 2330.9 2339.4 2358.1 2361.9 2362.0 2322.0 2335.7 2336.3 2336.6 2347.4 8803.6 8834.2 8872.9 8880.7 8884.2 5115.7 5282.1 5299.4 5306.0 5309.1 3490.9 3510.8 3548.8 3591.9 3594.6 1197.9 1209.7 1213.7 1234.6 2052.4 12181.3 12411.8 12440.9 12456.9 12465.4 4249.8 4556.0 4611.9 4617.4 4625.8 9678.1 9700.9 9722.0 9746.5 9757.3 5279.6 5284.7 5286.5 5291.3 5299.8 4291.7 4769.9 4924.5 4938.3 4952.0 3825.5 3955.9 3980.9 3993.1 4087.8 1206.5 1213.8 1215.3 1217.7 1245.0 895.9 926.6 932.6 968.2 991.5 5657.5 5927.6 5975.0 6094.8 6177.5 465.2 467.4 471.9 473.2 481.0 4090.4 4092.1 4110.3 4123.2 4144.7 10510.0 10791.8 10808.7 10809.0 10815.5 10693.6 10704.6 10734.0 10740.6 10806.7 8037.0 8044.5 8137.1 8149.6 8298.6 6132.4 6210.6 6258.4 6311.7 6350.2 10254.9 10271.8 10286.7 10299.2 10422.9 10378.9 10541.1 10553.9 10582.7 10678.9 7120.4 7337.0 7360.8 8135.8 8210.3 7571.8 7966.3 8663.8 8782.1 8837.0 7736.3 7779.3 7791.5 7841.0 8024.6 7563.1 7611.5 7611.5 7615.7 7618.2 7020.7 7062.0 7123.6 7127.9 7138.0 5044.3 5062.1 5158.1 5183.2 5207.1 5062.7 5121.2 5151.7 5263.1 5284.7 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | [#4] prototype.html Cycles:180 Average:4494.30 Median:4991.56 stddev:1310.28 (26.2%) stddev-sans-first:1275.72 19:19:22 INFO - PROCESS | 6038 | Values: 307.7 319.3 324.8 325.7 327.7 5237.4 5254.0 5279.8 5280.9 5297.4 4957.7 5015.2 5163.4 5166.8 5241.9 4125.5 4135.2 4140.1 4145.6 4151.0 4135.8 4142.1 4148.2 4151.2 4153.8 3882.5 3964.3 4079.6 4101.4 4136.6 4098.2 4102.1 4107.5 4121.2 4127.6 3654.6 3656.0 3657.7 3671.3 3672.5 4983.8 5006.5 5029.9 5116.9 5143.4 4657.7 4658.8 4669.3 4680.6 4701.0 5386.3 5403.9 5421.2 5448.4 5473.5 3730.6 3927.3 3995.7 4009.4 4025.3 5635.7 5661.5 5674.0 5676.4 5688.4 4002.7 4021.2 4036.8 4044.9 4065.6 3910.9 4122.9 4128.6 4132.6 4132.8 4643.3 4651.6 4666.8 4684.9 4697.7 5472.3 5506.4 5511.4 5514.9 5532.5 1339.2 1389.9 1395.4 1402.6 1414.7 886.7 898.8 905.8 907.4 912.5 4690.7 4708.8 4720.9 4730.4 4739.7 4952.4 4959.8 4968.0 4979.8 5000.7 4277.4 4659.2 4663.5 4678.3 4687.1 2581.8 2599.3 2623.0 2633.5 2638.4 4951.6 5078.9 5126.8 5137.8 5184.9 4999.3 5034.2 5036.4 5037.9 5108.2 5118.7 5122.8 5126.1 5132.4 5144.9 4920.7 4928.9 4932.7 4937.3 5162.3 5559.0 5579.9 5586.2 5592.6 5594.6 5855.6 5866.9 5873.3 5926.4 5941.2 3876.5 3952.2 3980.5 4011.6 4031.3 5354.9 5554.7 5608.5 5610.9 5627.6 5523.9 5528.3 5553.9 5561.8 5578.2 5286.7 5502.4 5511.3 5527.2 5533.3 5508.9 5509.9 5515.5 5520.4 5534.4 5516.2 5529.6 5564.4 5610.7 5624.6 5191.4 5415.9 5439.8 5487.8 5496.5 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - PROCESS | 6038 | [#5] yui.html Cycles:180 Average:21114.33 Median:4724.54 stddev:30147.72 (638.1%) stddev-sans-first:30193.60 19:19:22 INFO - PROCESS | 6038 | Values: 772.9 788.9 798.3 803.9 839.1 15681.3 17343.7 17345.9 17367.2 17367.2 15323.8 15600.4 15616.6 15634.6 15777.4 661.7 778.4 790.1 804.4 824.0 792.7 803.0 803.7 804.1 804.9 864.2 864.6 866.4 866.5 867.1 692.2 828.5 849.0 861.1 863.9 626.7 627.0 627.5 627.6 627.8 13689.2 13696.0 13703.5 13707.5 13731.1 13386.8 13758.2 13779.9 14031.3 14160.2 13690.3 13806.7 13876.5 14095.8 14652.1 4434.8 4489.6 4497.0 4575.7 4726.6 79054.0 79471.6 80146.0 84210.2 85480.0 10961.8 10965.3 11016.6 11090.3 11323.7 19560.9 19603.6 19681.8 19714.3 19765.3 8012.4 8030.3 8183.7 8229.8 8290.8 4662.8 4663.1 4722.5 4854.7 4883.7 3381.5 3507.5 3508.6 3510.4 3516.1 580.5 591.1 592.6 593.1 594.1 581.5 591.1 655.6 701.7 721.0 8888.5 9564.6 10995.8 11014.7 11149.3 676.7 779.9 786.4 787.9 815.0 4926.2 5001.3 5028.2 5040.2 5222.5 73242.3 75075.6 108340.9 118749.4 136087.0 63132.7 98189.0 110718.0 111131.0 112717.0 90539.5 91784.0 91831.0 93408.0 95435.5 9645.0 9668.6 9749.7 9758.0 9758.3 51948.5 52368.5 53150.7 53780.0 54464.5 68267.0 69926.6 76389.0 83628.0 84747.6 13786.3 14149.8 14168.1 14293.6 14327.4 15135.2 15551.6 15716.0 16000.0 16395.8 14147.0 14462.9 14632.0 14868.6 15386.0 14426.5 14757.9 14935.7 14997.9 15048.7 14015.8 14274.4 14495.3 14829.2 14840.2 11672.2 11727.5 11986.0 12016.0 12225.0 10163.3 10561.8 10689.9 11919.8 12485.7 19:19:22 INFO - PROCESS | 6038 | -------- Summary: end -------- 19:19:22 INFO - PROCESS | 6038 | 19:19:22 INFO - TEST-INFO | 6038: exit 0 19:19:22 INFO - TEST-OK | dromaeo_css | took 1115227ms 19:19:22 INFO - TEST-START | kraken 19:19:22 INFO - operating with platform_type : linux_ 19:19:22 INFO - Initialising browser for kraken test... 19:19:22 INFO - TEST-INFO | started process 6237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmposqXRS/profile http://localhost:36914/getInfo.html) 19:19:26 INFO - PROCESS | 6237 | __metrics Screen width/height:1600/1200 19:19:26 INFO - PROCESS | 6237 | colorDepth:24 19:19:26 INFO - PROCESS | 6237 | Browser inner width/height: 1024/697 19:19:26 INFO - PROCESS | 6237 | __metrics 19:19:27 INFO - PROCESS | 6237 | 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" 19:19:27 INFO - PROCESS | 6237 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:28 INFO - TEST-INFO | 6237: exit 0 19:19:28 INFO - Browser initialized. 19:19:28 INFO - Running cycle 1/1 for kraken test... 19:19:28 INFO - TEST-INFO | started process 6321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmposqXRS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:40 INFO - PROCESS | 6321 | RSS: Main: 155402240 19:19:40 INFO - PROCESS | 6321 | 19:19:42 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36914/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:43 INFO - PROCESS | 6321 | RSS: Main: 211890176 19:19:43 INFO - PROCESS | 6321 | 19:19:46 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36914/tests/kraken/driver.html?testName=audio-dft) 19:19:46 INFO - PROCESS | 6321 | RSS: Main: 190590976 19:19:46 INFO - PROCESS | 6321 | 19:19:50 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36914/tests/kraken/driver.html?testName=audio-fft) 19:19:50 INFO - PROCESS | 6321 | RSS: Main: 267358208 19:19:50 INFO - PROCESS | 6321 | 19:19:52 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36914/tests/kraken/driver.html?testName=audio-oscillator) 19:19:53 INFO - PROCESS | 6321 | RSS: Main: 178274304 19:19:53 INFO - PROCESS | 6321 | 19:19:55 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36914/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:19:56 INFO - PROCESS | 6321 | RSS: Main: 182788096 19:19:56 INFO - PROCESS | 6321 | 19:19:59 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36914/tests/kraken/driver.html?testName=imaging-darkroom) 19:19:59 INFO - PROCESS | 6321 | RSS: Main: 217710592 19:19:59 INFO - PROCESS | 6321 | 19:20:03 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36914/tests/kraken/driver.html?testName=imaging-desaturate) 19:20:03 INFO - PROCESS | 6321 | RSS: Main: 217927680 19:20:03 INFO - PROCESS | 6321 | 19:20:06 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36914/tests/kraken/driver.html?testName=json-parse-financial) 19:20:07 INFO - PROCESS | 6321 | RSS: Main: 218062848 19:20:07 INFO - PROCESS | 6321 | 19:20:08 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36914/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:20:09 INFO - PROCESS | 6321 | RSS: Main: 182849536 19:20:09 INFO - PROCESS | 6321 | 19:20:11 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:20:11 INFO - PROCESS | 6321 | RSS: Main: 188137472 19:20:11 INFO - PROCESS | 6321 | 19:20:13 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:20:14 INFO - PROCESS | 6321 | RSS: Main: 192397312 19:20:14 INFO - PROCESS | 6321 | 19:20:16 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:20:17 INFO - PROCESS | 6321 | RSS: Main: 186023936 19:20:17 INFO - PROCESS | 6321 | 19:20:20 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:20:20 INFO - PROCESS | 6321 | RSS: Main: 191012864 19:20:20 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:36914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36914/tests/kraken/driver.html?testName=ai-astar) 19:20:22 INFO - PROCESS | 6321 | RSS: Main: 241414144 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | __start_tp_report 19:20:22 INFO - PROCESS | 6321 | _x_x_mozilla_page_load 19:20:22 INFO - PROCESS | 6321 | _x_x_mozilla_page_load_details 19:20:22 INFO - PROCESS | 6321 | |i|pagename|runs| 19:20:22 INFO - PROCESS | 6321 | |0;ai-astar;101;112;111;111;112;113;112;110;112;112 19:20:22 INFO - PROCESS | 6321 | |1;audio-beat-detection;144;117;121;174;117;119;143;122;150;118 19:20:22 INFO - PROCESS | 6321 | |2;audio-dft;160;160;160;160;160;160;162;162;160;161 19:20:22 INFO - PROCESS | 6321 | |3;audio-fft;74;73;75;112;76;76;75;75;104;77 19:20:22 INFO - PROCESS | 6321 | |4;audio-oscillator;84;84;117;85;84;83;129;84;84;120 19:20:22 INFO - PROCESS | 6321 | |5;imaging-gaussian-blur;116;116;117;116;117;116;117;116;117;117 19:20:22 INFO - PROCESS | 6321 | |6;imaging-darkroom;145;146;146;146;146;145;147;146;145;146 19:20:22 INFO - PROCESS | 6321 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;95 19:20:22 INFO - PROCESS | 6321 | |8;json-parse-financial;53;50;50;51;49;53;51;51;54;52 19:20:22 INFO - PROCESS | 6321 | |9;json-stringify-tinderbox;63;62;63;62;62;62;63;63;63;63 19:20:22 INFO - PROCESS | 6321 | |10;stanford-crypto-aes;76;76;85;75;76;76;75;75;81;75 19:20:22 INFO - PROCESS | 6321 | |11;stanford-crypto-ccm;124;128;127;123;123;128;130;122;138;127 19:20:22 INFO - PROCESS | 6321 | |12;stanford-crypto-pbkdf2;158;157;158;155;153;157;156;162;161;162 19:20:22 INFO - PROCESS | 6321 | |13;stanford-crypto-sha256-iterative;68;64;63;62;66;62;65;64;64;64 19:20:22 INFO - PROCESS | 6321 | __end_tp_report 19:20:22 INFO - PROCESS | 6321 | __start_cc_report 19:20:22 INFO - PROCESS | 6321 | _x_x_mozilla_cycle_collect,1665 19:20:22 INFO - PROCESS | 6321 | __end_cc_report 19:20:22 INFO - PROCESS | 6321 | __startTimestamp1470882022676__endTimestamp 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | ------- Summary: start ------- 19:20:22 INFO - PROCESS | 6321 | Number of tests: 14 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.47 (3.1%) stddev-sans-first:0.87 19:20:22 INFO - PROCESS | 6321 | Values: 101.0 112.0 111.0 111.0 112.0 113.0 112.0 110.0 112.0 112.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:132.50 stddev:19.40 (14.6%) stddev-sans-first:20.12 19:20:22 INFO - PROCESS | 6321 | Values: 144.0 117.0 121.0 174.0 117.0 119.0 143.0 122.0 150.0 118.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 19:20:22 INFO - PROCESS | 6321 | Values: 160.0 160.0 160.0 160.0 160.0 160.0 162.0 162.0 160.0 161.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:14.03 (18.7%) stddev-sans-first:14.60 19:20:22 INFO - PROCESS | 6321 | Values: 74.0 73.0 75.0 112.0 76.0 76.0 75.0 75.0 104.0 77.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:84.00 stddev:18.60 (22.1%) stddev-sans-first:19.26 19:20:22 INFO - PROCESS | 6321 | Values: 84.0 84.0 117.0 85.0 84.0 83.0 129.0 84.0 84.0 120.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 19:20:22 INFO - PROCESS | 6321 | Values: 116.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 19:20:22 INFO - PROCESS | 6321 | Values: 145.0 146.0 146.0 146.0 146.0 145.0 147.0 146.0 145.0 146.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:20:22 INFO - PROCESS | 6321 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:1.58 (3.1%) stddev-sans-first:1.56 19:20:22 INFO - PROCESS | 6321 | Values: 53.0 50.0 50.0 51.0 49.0 53.0 51.0 51.0 54.0 52.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 19:20:22 INFO - PROCESS | 6321 | Values: 63.0 62.0 63.0 62.0 62.0 62.0 63.0 63.0 63.0 63.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:76.00 stddev:3.33 (4.4%) stddev-sans-first:3.52 19:20:22 INFO - PROCESS | 6321 | Values: 76.0 76.0 85.0 75.0 76.0 76.0 75.0 75.0 81.0 75.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:127.50 stddev:4.69 (3.7%) stddev-sans-first:4.85 19:20:22 INFO - PROCESS | 6321 | Values: 124.0 128.0 127.0 123.0 123.0 128.0 130.0 122.0 138.0 127.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:158.00 stddev:3.00 (1.9%) stddev-sans-first:3.18 19:20:22 INFO - PROCESS | 6321 | Values: 158.0 157.0 158.0 155.0 153.0 157.0 156.0 162.0 161.0 162.0 19:20:22 INFO - PROCESS | 6321 | 19:20:22 INFO - PROCESS | 6321 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.81 (2.8%) stddev-sans-first:1.30 19:20:22 INFO - PROCESS | 6321 | Values: 68.0 64.0 63.0 62.0 66.0 62.0 65.0 64.0 64.0 64.0 19:20:22 INFO - PROCESS | 6321 | -------- Summary: end -------- 19:20:22 INFO - PROCESS | 6321 | 19:20:23 INFO - TEST-INFO | 6321: exit 0 19:20:23 INFO - TEST-OK | kraken | took 60741ms 19:20:23 INFO - SUITE-END | took 1175s 19:20:23 INFO - Completed test suite (00:19:36) 19:20:23 INFO - javascript benchmark 19:20:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3083.0750774767566, 3092.5515800208955, 3122.4379611669897, 3123.1099136746034, 3137.043201823443, 5330.3603567571845, 5333.413324534303, 5378.408375078739, 5420.972895135524, 5452.0458919689, 5452.91820622691, 5460.071787796074, 5497.780088796443, 5515.0, 5518.392976772562, 4922.655414121004, 4970.6520543561965, 5021.071101846152, 5022.723750193738, 5116.488351164872, 5011.1481048221885, 5055.368078990126, 5056.823011194609, 5070.492950704918, 5077.619178561604, 4887.315775791392, 4890.217565189588, 4969.8012079516775, 4971.577415919651, 5042.647014708972, 4881.316615673773, 4893.902121957576, 4903.141950158708, 4915.901681966377, 4980.703269709892, 4917.385326834145, 4966.850994470172, 4981.750912454399, 4994.650374473752, 5011.198208286657, 5366.221897824833, 5404.270423492818, 5406.864828379283, 5448.4279150689, 5453.536449401806, 5815.592908496407, 5822.446867547576, 5852.531797456193, 5865.736041878125, 5872.677002764846, 5898.174255604219, 5911.29064512257, 5914.408559144094, 5921.259842519685, 5921.674307913063, 5340.465953404668, 5394.838154855361, 5442.074847275893, 5442.673439593637, 5543.805966791224, 6030.33666296707, 6031.74287870956, 6046.91123813687, 6088.025915853442, 6098.237720284965, 5782.190493330937, 5799.33307669615, 5825.446582574648, 5851.444112809277, 5852.063669812809, 5625.46558076982, 5681.34664513578, 5774.047282198474, 5775.220345253421, 5875.500582450454, 5759.85600359986, 5802.506786923159, 5805.332386775575, 5809.767609295665, 5823.79616713413, 5766.135079737989, 5805.825825225165, 5815.302163740378, 5824.621399609012, 5839.153322768175, 4394.318880573516, 4421.801018954155, 4427.424434823452, 4468.843590474317, 4474.530174331649, 4005.1989602079702, 4022.054817117975, 4023.7786921719585, 4025.476688030596, 4027.1140349123148, 5402.243685883979, 5425.810096646598, 5439.265699130646, 5517.641353312022, 5539.639923404966, 5828.067509198508, 5833.300003999547, 5837.970810145922, 5842.678652674143, 5844.1233814927255, 5444.727763611882, 5474.206240095164, 5500.0, 5512.91730624041, 5513.61404701667, 4909.435414927258, 4957.7769000395065, 4994.350734404504, 4996.400431948116, 5064.873378165502, 6080.422359875804, 6082.817515474543, 6084.178635884187, 6086.178365920633, 6087.96956015226, 5821.970890145522, 5822.737976791139, 5841.737121829613, 5864.648121112747, 5877.559183061202, 5919.763209471573, 6008.279006519071, 6047.576669633084, 6059.939400605937, 6114.631938157225, 5754.136879467946, 5757.193992841089, 5762.913556296575, 5768.048272035067, 5821.417858214145, 5843.1235314703135, 5861.560382971379, 5861.7655293787775, 5872.354041055395, 5872.618279811799, 5590.077637189818, 5686.545076393962, 5686.943130568641, 5714.971425143014, 5788.884222315614, 5665.405132461032, 5707.971460142673, 5708.400617935057, 5709.857253568528, 5718.999175144416, 5722.828315150552, 5727.656340619576, 5731.426857314235, 5738.196652468741, 5741.110127930177, 5605.271314729059, 5721.256236689203, 5758.971205143947, 5775.537956963349, 5856.297244330707, 5659.745311460912, 5683.744231509675, 5685.061964775931, 5690.487856092972, 5691.288588926384], "value": 5384.809511287711, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.356247783721, 584.5937073733754, 608.4493533352318, 611.1688104178317, 620.0667994668024, 33297.0, 44206.33690494645, 45164.322535162, 45221.54778452215, 45222.09555808882, 45762.771186144026, 46333.53666463334, 46346.536534634644, 46374.0, 46374.536254637445, 8642.43824151428, 8755.430896991675, 8766.123387661246, 8771.254443372289, 8777.780555486132, 8970.86543701845, 9075.909240907606, 9083.909160908372, 9085.13691199335, 9087.136722011432, 9130.726078217664, 9159.358844880828, 9171.954140229289, 9180.403273787217, 9196.218321442686, 9061.13919177677, 9102.13529714676, 9109.134632209929, 9111.13444222801, 9113.0, 8960.775980600472, 9048.954755226248, 9062.909370906273, 9072.183503484652, 9072.591733372014, 43115.56884431147, 43379.78310108429, 43504.347434788506, 43510.564894351286, 43554.564454355685, 3573.065046319349, 3745.2696724138805, 3826.521684789401, 3860.613938606145, 4039.5290369153236, 32643.836780815946, 33514.6648533514, 33913.0, 33930.49104263438, 34014.0, 4387.780610969471, 4480.059187570614, 4508.887277818081, 4583.747893865836, 4669.276262179367, 365877.0, 365945.0, 366717.0, 366829.0, 367898.0, 57134.71432642893, 57258.427415726146, 57619.25474844122, 57638.42361576414, 57686.0, 60401.39598604046, 60446.46651501155, 60503.0, 60519.39480605138, 60545.39454605486, 16894.23975921062, 17002.659946801243, 17031.063291518836, 17119.65760684779, 17186.226619801888, 13212.141210821457, 13387.866121338662, 13399.263040532669, 13430.597082087554, 13432.328383580782, 14487.78268325976, 15755.212239387985, 15775.0, 15921.840781592267, 15988.76016859748, 6425.554250293647, 6434.646094464756, 6435.195600549931, 6558.508111891627, 6580.473562115019, 1006.9496525173859, 1045.9947700261603, 1046.989654982945, 1074.6114350188057, 1167.3521195736448, 29932.551011734842, 30192.84903575512, 30591.541126883116, 30941.381172376878, 31037.68962310393, 1105.690406686129, 1121.3440137519642, 1128.90968722502, 1152.8674202466657, 1170.4908364861312, 16057.598560035858, 16262.430814921421, 16267.51197464078, 16543.0, 16649.667006659798, 510484.0, 514539.9822012423, 516037.0, 517386.0, 518659.0, 60301.69849150726, 60460.0, 60533.0, 60534.0, 60557.39442605517, 1233.4018001269378, 1338.070041321292, 1370.136813807295, 1398.3497673581833, 1415.8088658031033, 28742.568861466676, 31473.8426307867, 31581.210469738442, 31712.524312135796, 32177.839110804296, 49637.50362496329, 50632.24051639155, 50767.23849142192, 50812.0, 50968.49031509786, 404829.0, 407038.0, 407278.0, 407772.0, 408504.0, 28545.714542854304, 42277.0, 42430.15139697127, 42554.0, 42737.358939616555, 47439.0, 49346.75326623344, 49993.50006499889, 50029.0, 50313.0, 41263.793681031406, 42249.0, 42925.57074429341, 43039.354409683256, 44099.0, 41382.172356552095, 41802.79098604609, 42581.36127958021, 43273.3508997359, 43349.56650433455, 40216.59783402128, 40665.39001915033, 41516.58483415248, 41767.164656707304, 41789.37315940324, 35794.463083054296, 37338.31977560962, 38148.237035258586, 38279.808600956814, 38732.0, 25473.23580292594, 25512.744872551037, 25569.74430255748, 25810.225693229233, 26129.60805587956], "value": 20320.662225200045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.7997681252124, 380.52244433236285, 390.3033085941594, 395.9821808018639, 416.7020580285097, 16968.151592420378, 19647.24938508608, 19669.508262293413, 19908.70136947945, 21158.365249042516, 19119.23523059076, 19312.420627381165, 19933.601327973432, 19986.40040798775, 20083.59832803343, 5256.474352564751, 5832.7958521451865, 5971.611845230068, 6008.909866352008, 6022.457978781908, 4283.271843786564, 5856.033754430514, 6295.653739044351, 6653.567518111331, 6673.699683514254, 6699.229588597325, 6769.830754231134, 6772.762953296636, 6774.085498457719, 6787.864242715143, 6576.177977752781, 6693.732250709966, 6706.329367063304, 6720.596764194164, 6747.08914296571, 5866.354700982888, 5868.970655146697, 5910.704464776744, 5914.704264786744, 5915.822525324247, 23599.88200058989, 23763.524729505312, 24795.380115497075, 24848.503029939482, 24981.125660601974, 2324.2097686786574, 2402.4114092047384, 2469.876506174702, 2500.0124950644577, 2504.2362079565723, 14554.908381871403, 15175.696486070217, 15268.923655381763, 15476.690466190727, 15796.763048554281, 1927.2098439639822, 1994.9401517954484, 1995.5809280051208, 2011.6580181369202, 2037.9184832606677, 82629.17370826275, 84798.15201847964, 85175.57412212962, 87649.56175219083, 88696.556517217, 10583.153347732163, 10666.78666426667, 10842.644669416322, 10887.673369798918, 11259.549618015188, 15505.612359691098, 15508.302126404338, 15509.379624815107, 15526.146061966474, 15531.378744850079, 8056.234657707509, 8073.798155046171, 8297.709580164665, 8311.127331630212, 8333.583320833934, 7777.300042996158, 7939.325157361689, 7950.165232650604, 8174.509529428254, 8197.590120493951, 6724.562903411263, 6729.528932974743, 6808.421284190899, 6878.105846239957, 6896.310368963063, 1768.938087166943, 1828.838225798451, 1914.808519148074, 1995.9800401995794, 1997.1412292713983, 894.7136916186757, 968.864358989742, 974.2546951582045, 999.5452069308448, 1034.3380236648552, 17034.48896533106, 18638.813611863978, 19062.904685476762, 19197.520061998282, 19460.805391945898, 971.4074414607084, 1001.4742260313394, 1003.4180175498193, 1004.3672486333563, 1067.8985496377832, 7918.920810791934, 11017.283876548, 11132.220744547803, 11143.164262680331, 11155.386453745123, 515580.0, 531063.0, 534403.0, 535517.0, 536036.0, 91168.0, 92941.53529232446, 93134.53432732793, 93362.53318733363, 93820.53089734507, 1018.8573599696049, 1062.1555863088863, 1085.8262677971486, 1088.0751361342795, 1094.9288296260718, 16366.509004729876, 16418.425355112515, 17165.399211027554, 17667.38164164248, 17841.46475605734, 41605.58394416017, 42212.78893605512, 42864.0, 43053.56946430621, 43069.35395969126, 59805.40194597998, 59823.700881495315, 60144.39855601563, 60308.396916032034, 61723.0, 7812.765617031499, 8278.586070696321, 8726.869096963424, 8742.125787421208, 8906.777330566787, 13748.93125534406, 13796.0, 13875.236861972688, 14043.929780351033, 14603.123812571279, 11800.17398782077, 11894.940525297317, 12190.146689731633, 12238.694032649255, 12295.631131066082, 12029.338386388483, 12083.214591051554, 12088.939555302168, 12276.693082673004, 12367.196132251374, 11843.881561184278, 12129.0, 12137.878621214028, 12198.81701774492, 12549.623511294676, 7707.730229441942, 7851.2148785121035, 7930.563818990011, 7934.444588878723, 8004.5597492138495, 9984.201263899016, 11639.825402618799, 11655.18413711032, 12327.9383603085, 12685.302308372758], "value": 9643.033234194516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.8920886329095, 1482.9777553336696, 1522.5660686704289, 1643.4740882917463, 1800.4508624869409, 8429.325653947684, 8672.956635216822, 8820.60307286172, 8980.506072166045, 9042.050584688612, 8922.06318336575, 8924.375293729441, 8924.687635932743, 8930.240929520998, 8952.731418057452, 2413.54142712884, 2443.547943630434, 2445.960466801611, 2446.1193970170725, 2446.156076153724, 2404.7955923746504, 2445.0097710427303, 2450.779429851313, 2452.3869032741813, 2460.8031357491445, 2374.620060790274, 2375.061850569024, 2402.903883844644, 2404.927852164438, 2407.987960060197, 2330.860148391085, 2339.4268404240984, 2358.1157066100213, 2361.9409514762183, 2362.01976179886, 2321.97678023221, 2335.708036495438, 2336.2991102669303, 2336.556054349685, 2347.3896786835376, 8803.64785408583, 8834.204921557026, 8872.911270887338, 8880.689175878877, 8884.156005179497, 5115.667481613683, 5282.128448805943, 5299.3905700844125, 5305.973470132663, 5309.097453432925, 3490.947635785465, 3510.8420121094614, 3548.7515873888838, 3591.856325746967, 3594.622564630702, 1197.8502687164105, 1209.7217639942774, 1213.7451135261606, 1234.6172686467223, 2052.3842847145947, 12181.269123852597, 12411.813822792665, 12440.875591244063, 12456.937715311456, 12465.439055242536, 4249.808758605808, 4556.0, 4611.930821037688, 4617.376654151713, 4625.791839367236, 9678.080582344666, 9700.854487182698, 9721.951390243003, 9746.512674366395, 9757.316987810927, 5279.551238144801, 5284.656497327662, 5286.497782710637, 5291.3385826771655, 5299.788008479618, 4291.678124140702, 4769.856904292877, 4924.482929292444, 4938.259261110862, 4951.950480495221, 3825.464434979105, 3955.861544845974, 3980.9203815924097, 3993.101552150801, 4087.795610219477, 1206.5113628980278, 1213.83006379107, 1215.3194211241732, 1217.6712287682276, 1244.9564265250672, 895.923846473044, 926.5969303352928, 932.5616960028775, 968.2496065549255, 991.4992928571025, 5657.4908258256955, 5927.614705044159, 5974.970125149346, 6094.756209751649, 6177.537131786868, 465.1952122827527, 467.40872795913197, 471.9457262414866, 473.2121018504221, 480.97595120244546, 4090.365993271048, 4092.1201941582704, 4110.321796903538, 4123.175364927027, 4144.730592511477, 10510.001549852752, 10791.83812242817, 10808.675739727822, 10809.0, 10815.459227038617, 10693.572257109785, 10704.571817127384, 10734.033936945712, 10740.624078157227, 10806.675799726021, 8036.959815201004, 8044.477108987897, 8137.104918458965, 8149.551774652451, 8298.626561804855, 6132.359753151537, 6210.596311239755, 6258.405451482102, 6311.684415779285, 6350.206224221973, 10254.897451025692, 10271.845922311022, 10286.742831429274, 10299.227557933335, 10422.947885260524, 10378.948105259426, 10541.104006159563, 10553.894461055292, 10582.682519524427, 10678.946605266923, 7120.430365570845, 7337.046183996046, 7360.815979600553, 8135.837283254184, 8210.3021243195, 7571.848563028599, 7966.322862556747, 8663.826723465369, 8782.121787821166, 8837.0, 7736.2650548197835, 7779.299863012356, 7791.532508049535, 7840.960795195988, 8024.638891249791, 7563.092428908566, 7611.505252158592, 7611.543307401574, 7615.7334493292465, 7618.238176182337, 7020.719171233093, 7062.0, 7123.572585644878, 7127.8574428512175, 7138.036365090854, 5044.344235249394, 5062.114130027304, 5158.123119069842, 5183.222516622386, 5207.1147904857025, 5062.746862656779, 5121.206213036985, 5151.665141765773, 5263.131583288715, 5284.735763211747], "value": 4601.428509571112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.69230769230785, 319.32940824269036, 324.8408279942828, 325.66456549753764, 327.6903326356593, 5237.423883372826, 5253.973730131349, 5279.815206467774, 5280.9207861882105, 5297.390800057994, 4957.652964292501, 5015.222640490721, 5163.380394352683, 5166.819161329354, 5241.9213711794355, 4125.504939407276, 4135.1936372407245, 4140.089180380319, 4145.606167414106, 4151.045259590281, 4135.813888375015, 4142.130152667944, 4148.232576973254, 4151.169766046788, 4153.792310384484, 3882.514685664292, 3964.275540140034, 4079.5920407959266, 4101.425800387948, 4136.627703506684, 4098.200850834089, 4102.056526998792, 4107.54816970133, 4121.216968775923, 4127.649149822269, 3654.6162652921594, 3655.976326628548, 3657.65252301031, 3671.3382797471886, 3672.485851980725, 4983.825566105169, 5006.499350065001, 5029.949700503022, 5116.923246151311, 5143.434222235551, 4657.720536767804, 4658.790354434024, 4669.276262179367, 4680.64895132863, 4701.0127873146685, 5386.326709161355, 5403.891922161574, 5421.213923981, 5448.4006759256845, 5473.452654734535, 3730.608286129944, 3927.293087244295, 3995.720299579031, 4009.4386785850006, 4025.335819589764, 5635.690037047961, 5661.490465858052, 5674.035413979634, 5676.432356764331, 5688.4311568843195, 4002.699797515198, 4021.235965166609, 4036.8385264589674, 4044.8988775280263, 4065.573114822901, 3910.9413358799643, 4122.896927576775, 4128.566500517462, 4132.628063474242, 4132.834686612562, 4643.349931009594, 4651.581357677757, 4666.8133274668635, 4684.906301873943, 4697.741624210701, 5472.315960504937, 5506.421825708323, 5511.4213007633625, 5514.862128446821, 5532.474414930575, 1339.2366351179928, 1389.9297540893456, 1395.3651088754593, 1402.6283034995733, 1414.7312010718138, 886.7029545102334, 898.8247291778172, 905.813384466338, 907.419251678926, 912.4707669551644, 4690.695104818176, 4708.835190768306, 4720.881977950578, 4730.432348118248, 4739.66822322441, 4952.430470495867, 4959.82640607577, 4967.975160124249, 4979.825706100269, 5000.67495612784, 4277.422547956048, 4659.2312268476, 4663.48701642819, 4678.345031695565, 4687.1328804171335, 2581.7934565234737, 2599.271484462849, 2622.9639292479364, 2633.5391306521283, 2638.379980704533, 4951.603871690256, 5078.949210507848, 5126.769295381717, 5137.794488220504, 5184.870378240499, 4999.300517616937, 5034.244863270539, 5036.445990940993, 5037.858909963251, 5108.233764935215, 5118.667286626357, 5122.846314610568, 5126.128558145124, 5132.435432102466, 5144.945286216242, 4920.6555541111775, 4928.890999525078, 4932.654714169974, 4937.259411088366, 5162.277281180563, 5559.0, 5579.888402231852, 5586.245856809279, 5592.636478628876, 5594.580406469612, 5855.590108692521, 5866.882662346815, 5873.295204575478, 5926.377730338252, 5941.197938278277, 3876.5348158221195, 3952.189801090712, 3980.5024371953505, 4011.578784227731, 4031.254217969685, 5354.892902142014, 5554.694491802989, 5608.46719561641, 5610.943890561042, 5627.564970932386, 5523.917141242965, 5528.281323427928, 5553.861763221108, 5561.777528898961, 5578.219049333015, 5286.735663216747, 5502.449755024626, 5511.338639363302, 5527.198556209408, 5533.253010843485, 5508.862278443071, 5509.944900551103, 5515.4760297771645, 5520.392756796668, 5534.363548192009, 5516.172574113915, 5529.640573362719, 5564.415736347625, 5610.663360198238, 5624.578156638187, 5191.402988656202, 5415.864603384789, 5439.782408703766, 5487.835364939058, 5496.450354964471], "value": 4075.8008870017634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.9033870766154, 788.9408294377921, 798.3373799746212, 803.8593246181921, 839.0602525171809, 15681.29434175462, 17343.73984390232, 17345.913270433644, 17367.2184751686, 17367.218475168615, 15323.770143447857, 15600.375984960616, 15616.609584760386, 15634.609134771636, 15777.368905243777, 661.7154623511888, 778.404520541786, 790.063774427304, 804.4328748232482, 824.0358780227149, 792.7106606088765, 803.028335713787, 803.7227156630953, 804.0873608454417, 804.9436539442241, 864.1703964194381, 864.6152462154344, 866.3805379153904, 866.4974314897376, 867.1458613265928, 692.1590267824618, 828.457360428914, 848.9830203395898, 861.0829466618031, 863.9481631102122, 626.7023163997111, 627.0343670747044, 627.5356236385043, 627.6077451592755, 627.77086363477, 13689.247091409969, 13696.0, 13703.520376786764, 13707.45170193191, 13731.107478013897, 13386.799198012037, 13758.243296618683, 13779.862201377959, 14031.298435078204, 14160.150390976574, 13690.315484225748, 13806.723865522634, 13876.514321998782, 14095.78856317156, 14652.120872747562, 4434.778261086933, 4489.595936365743, 4496.955030449719, 4575.679702420832, 4726.574608285271, 79054.0, 79471.602641987, 80146.0, 84210.15789842085, 85480.0, 10961.780764384668, 10965.287256328313, 11016.559337626566, 11090.334579925231, 11323.716907077389, 19560.902195489216, 19603.607927841364, 19681.803181968284, 19714.309999149962, 19765.308214212433, 8012.3590112790835, 8030.317423018992, 8183.71357002502, 8229.835403291901, 8290.751277461686, 4662.790174442158, 4663.137319595886, 4722.504137065559, 4854.708717476892, 4883.682560633548, 3381.5434916286476, 3507.5264839246884, 3508.649135086471, 3510.3856825055514, 3516.1209697575605, 580.5268706004592, 591.1457943271931, 592.552622769806, 593.104412337365, 594.1147689942042, 581.5376775463517, 591.1398914579277, 655.5607742812327, 701.6877489517218, 721.0, 8888.466691998372, 9564.56959436827, 10995.835062474069, 11014.724631884106, 11149.275297105662, 676.6988690032888, 779.8986131802943, 786.3905473258156, 787.8857565652949, 814.9918500814916, 4926.211806111005, 5001.324821149171, 5028.220625803006, 5040.243963405518, 5222.451642577551, 73242.26757732354, 75075.62462187654, 108340.91659083308, 118749.40625296818, 136087.0, 63132.68433657802, 98189.0, 110718.0, 111131.0, 112717.0, 90539.54730226439, 91784.0, 91831.0, 93408.0, 95435.52282238683, 9644.95177524108, 9668.564914579001, 9749.658761943581, 9758.0, 9758.268129890144, 51948.480515194366, 52368.47631523636, 53150.734246328524, 53780.0, 54464.45535544594, 68267.0, 69926.55527799913, 76389.0, 83628.0, 84747.5762621183, 13786.310684465536, 14149.787753183504, 14168.149911005372, 14293.57119286423, 14327.355269013127, 15135.243237838284, 15551.611209719847, 15716.0, 16000.0, 16395.83604163991, 14147.0, 14462.927685361507, 14632.0, 14868.62828429298, 15386.0, 14426.495072672406, 14757.852421476076, 14935.701285974437, 14997.940252430768, 15048.699026019638, 14015.789763153765, 14274.357653905392, 14495.347709352893, 14829.184394858386, 14840.18378989166, 11672.241304315192, 11727.47226374798, 11986.016479163254, 12015.977420175135, 12225.0, 10163.339382940085, 10561.841572376266, 10689.9465502672, 11919.82120268214, 12485.687857803265], "value": 7724.086319603454, "unit": "score"}], "name": "dromaeo_css", "value": 7312.295718356142, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 111.0, 111.0, 112.0, 113.0, 112.0, 110.0, 112.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 121.0, 174.0, 117.0, 119.0, 143.0, 122.0, 150.0, 118.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 160.0, 160.0, 162.0, 162.0, 160.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 112.0, 76.0, 76.0, 75.0, 75.0, 104.0, 77.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 117.0, 85.0, 84.0, 83.0, 129.0, 84.0, 84.0, 120.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 51.0, 49.0, 53.0, 51.0, 51.0, 54.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0, 63.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 85.0, 75.0, 76.0, 76.0, 75.0, 75.0, 81.0, 75.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 127.0, 123.0, 123.0, 128.0, 130.0, 122.0, 138.0, 127.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 158.0, 155.0, 153.0, 157.0, 156.0, 162.0, 161.0, 162.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 63.0, 62.0, 66.0, 62.0, 65.0, 64.0, 64.0, 64.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1478.0, "alertThreshold": 2.0}]} 19:20:23 INFO - Return code: 0 19:20:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:20:23 INFO - # TBPL SUCCESS # 19:20:23 INFO - Running post-action listener: _resource_record_post_action 19:20:23 INFO - [mozharness: 2016-08-11 02:20:23.970303Z] Finished run-tests step (success) 19:20:23 INFO - Running post-run listener: _resource_record_post_run 19:20:24 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 550510592; Read time: 12; Write time: 474668 19:20:24 INFO - TinderboxPrint: CPU usage
13.1% 19:20:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:20:24 INFO - TinderboxPrint: I/O write bytes / time
550,510,592 / 474,668 19:20:24 INFO - TinderboxPrint: CPU idle
8,277.8 (86.9%) 19:20:24 INFO - TinderboxPrint: CPU user
1,197.9 (12.6%) 19:20:24 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:24 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256999424; Read time: 0; Write time: 258832 19:20:24 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 292986880; Read time: 0; Write time: 215712 19:20:24 INFO - Running post-run listener: _upload_blobber_files 19:20:24 INFO - Blob upload gear active. 19:20:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:20:25 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:20:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:25 INFO - (blobuploader) - INFO - Done attempting. 19:20:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:20:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:20:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:26 INFO - (blobuploader) - INFO - Done attempting. 19:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:30 INFO - (blobuploader) - INFO - Done attempting. 19:20:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:30 INFO - Return code: 0 19:20:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/792cf3b165825edfcee320d85dd442fdaadf6b09c648583cb228b85d23746bea71b3e830def88c396cbbd6b7ea7a4e7a775ce4e2320e103d2cf6e596537f17e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/074cd06b1417490b7fb492834c128a79cfb7d9d381d2dde5b30998612ad69e491a0d216e4eb231f529bcdb70141f3c5c9f2a5d69a157ad9ecab3e88869df2777", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd644ecd1aecf928a48455426ebe52b064a8c4340e0a7bca15892c115ed0d165368e6ac94eacd8598afe83126acf3727da36238217f13cc56b21f64339065f1"} 19:20:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:30 INFO - Contents: 19:20:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/792cf3b165825edfcee320d85dd442fdaadf6b09c648583cb228b85d23746bea71b3e830def88c396cbbd6b7ea7a4e7a775ce4e2320e103d2cf6e596537f17e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/074cd06b1417490b7fb492834c128a79cfb7d9d381d2dde5b30998612ad69e491a0d216e4eb231f529bcdb70141f3c5c9f2a5d69a157ad9ecab3e88869df2777", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd644ecd1aecf928a48455426ebe52b064a8c4340e0a7bca15892c115ed0d165368e6ac94eacd8598afe83126acf3727da36238217f13cc56b21f64339065f1"} 19:20:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:30 INFO - Copying logs to upload dir... 19:20:30 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.548769 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-10 19:20:30.091658) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 19:20:30.096559) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688 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/792cf3b165825edfcee320d85dd442fdaadf6b09c648583cb228b85d23746bea71b3e830def88c396cbbd6b7ea7a4e7a775ce4e2320e103d2cf6e596537f17e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/074cd06b1417490b7fb492834c128a79cfb7d9d381d2dde5b30998612ad69e491a0d216e4eb231f529bcdb70141f3c5c9f2a5d69a157ad9ecab3e88869df2777", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd644ecd1aecf928a48455426ebe52b064a8c4340e0a7bca15892c115ed0d165368e6ac94eacd8598afe83126acf3727da36238217f13cc56b21f64339065f1"} build_url:https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011488 build_url: 'https://queue.taskcluster.net/v1/task/cKko4EqwT7KhIQ2-XKISJA/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/792cf3b165825edfcee320d85dd442fdaadf6b09c648583cb228b85d23746bea71b3e830def88c396cbbd6b7ea7a4e7a775ce4e2320e103d2cf6e596537f17e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/074cd06b1417490b7fb492834c128a79cfb7d9d381d2dde5b30998612ad69e491a0d216e4eb231f529bcdb70141f3c5c9f2a5d69a157ad9ecab3e88869df2777", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd644ecd1aecf928a48455426ebe52b064a8c4340e0a7bca15892c115ed0d165368e6ac94eacd8598afe83126acf3727da36238217f13cc56b21f64339065f1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 19:20:30.121908) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 19:20:30.122239) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470878714.511476-1022780688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004351 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 19:20:30.205925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 19:20:30.206963) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 19:20:30.207262) ========= ========= Total master_lag: 0.18 =========