builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-056 starttime: 1470842218.1 results: success (0) buildid: 20160810065516 builduid: d6d6d1de88bc4ed8a4773507395c313a revision: c12bb83ad278a7884af9c70c3622dc6b4c819eaf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.096823) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.097445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.097787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.114147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.114484) ========= 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=52faae308a556c440401f8e5000001e8-1470842218.891614-179384487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 08:16:59-- 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% 46.5M=0s 2016-08-10 08:16:59 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.213186) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.213580) ========= 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=52faae308a556c440401f8e5000001e8-1470842218.891614-179384487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019639 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.289933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.290399) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c12bb83ad278a7884af9c70c3622dc6b4c819eaf --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 c12bb83ad278a7884af9c70c3622dc6b4c819eaf --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=52faae308a556c440401f8e5000001e8-1470842218.891614-179384487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 08:16:59,950 truncating revision to first 12 chars 2016-08-10 08:16:59,950 Setting DEBUG logging. 2016-08-10 08:16:59,950 attempt 1/10 2016-08-10 08:16:59,950 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c12bb83ad278?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 08:17:00,250 unpacking tar archive at: mozilla-central-c12bb83ad278/testing/mozharness/ program finished with exit code 0 elapsedTime=0.618861 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.924639) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.925002) ========= script_repo_revision: c12bb83ad278a7884af9c70c3622dc6b4c819eaf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.925516) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.925837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 08:16:58.936568) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-10 08:16:58.936923) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=52faae308a556c440401f8e5000001e8-1470842218.891614-179384487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:17:00 INFO - MultiFileLogger online at 20160810 08:17:00 in /builds/slave/test 08:17:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:17:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:17:00 INFO - {'append_to_log': False, 08:17:00 INFO - 'base_work_dir': '/builds/slave/test', 08:17:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:17:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:17:00 INFO - 'branch': 'Firefox-Non-PGO', 08:17:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:17:00 INFO - 'config_files': ('talos/linux_config.py',), 08:17:00 INFO - 'default_actions': ('clobber', 08:17:00 INFO - 'read-buildbot-config', 08:17:00 INFO - 'download-and-extract', 08:17:00 INFO - 'populate-webroot', 08:17:00 INFO - 'create-virtualenv', 08:17:00 INFO - 'install', 08:17:00 INFO - 'run-tests'), 08:17:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:17:00 INFO - 'download_minidump_stackwalk': True, 08:17:00 INFO - 'download_symbols': 'ondemand', 08:17:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:17:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:17:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:17:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:17:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:17:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:17:00 INFO - 'installer_path': 'installer.exe', 08:17:00 INFO - 'log_level': 'info', 08:17:00 INFO - 'log_name': 'talos', 08:17:00 INFO - 'log_to_console': True, 08:17:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:17:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:17:00 INFO - 'opt_config_files': (), 08:17:00 INFO - 'pip_index': False, 08:17:00 INFO - 'sps_profile': False, 08:17:00 INFO - 'sps_profile_interval': 0, 08:17:00 INFO - 'suite': 'dromaeojs', 08:17:00 INFO - 'system_bits': '32', 08:17:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:17:00 INFO - 'title': 'talos-linux64-ix-056', 08:17:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:17:00 INFO - 'use_talos_json': True, 08:17:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:17:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:17:00 INFO - 'work_dir': 'build'} 08:17:00 INFO - [mozharness: 2016-08-10 15:17:00.694299Z] Running clobber step. 08:17:00 INFO - Running pre-action listener: _resource_record_pre_action 08:17:00 INFO - Running main action method: clobber 08:17:00 INFO - rmtree: /builds/slave/test/build 08:17:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:17:01 INFO - Running post-action listener: _resource_record_post_action 08:17:01 INFO - [mozharness: 2016-08-10 15:17:01.173595Z] Finished clobber step (success) 08:17:01 INFO - [mozharness: 2016-08-10 15:17:01.173715Z] Running read-buildbot-config step. 08:17:01 INFO - Running pre-action listener: _resource_record_pre_action 08:17:01 INFO - Running main action method: read_buildbot_config 08:17:01 INFO - Using buildbot properties: 08:17:01 INFO - { 08:17:01 INFO - "project": "", 08:17:01 INFO - "product": "firefox", 08:17:01 INFO - "script_repo_revision": "production", 08:17:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:17:01 INFO - "repository": "", 08:17:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:17:01 INFO - "buildid": "20160810065516", 08:17:01 INFO - "pgo_build": "False", 08:17:01 INFO - "basedir": "/builds/slave/test", 08:17:01 INFO - "buildnumber": 672, 08:17:01 INFO - "slavename": "talos-linux64-ix-056", 08:17:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:17:01 INFO - "platform": "ubuntu64_hw", 08:17:01 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:17:01 INFO - "branch": "mozilla-central", 08:17:01 INFO - "repo_path": "mozilla-central", 08:17:01 INFO - "revision": "c12bb83ad278a7884af9c70c3622dc6b4c819eaf", 08:17:01 INFO - "stage_platform": "linux64", 08:17:01 INFO - "builduid": "d6d6d1de88bc4ed8a4773507395c313a", 08:17:01 INFO - "slavebuilddir": "test" 08:17:01 INFO - } 08:17:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:17:01 INFO - Running post-action listener: _resource_record_post_action 08:17:01 INFO - [mozharness: 2016-08-10 15:17:01.176380Z] Finished read-buildbot-config step (success) 08:17:01 INFO - [mozharness: 2016-08-10 15:17:01.176469Z] Running download-and-extract step. 08:17:01 INFO - Running pre-action listener: _resource_record_pre_action 08:17:01 INFO - Running main action method: download_and_extract 08:17:01 INFO - mkdir: /builds/slave/test/build/tests 08:17:01 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')]} 08:17:01 INFO - trying https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:17:01 INFO - Downloading https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:01 INFO - Downloaded 1361 bytes. 08:17:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:17:01 INFO - Using the following test package requirements: 08:17:01 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:17:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:17:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:17:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'jsshell-linux-x86_64.zip'], 08:17:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:17:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:17:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:17:01 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:17:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:17:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:17:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:17:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:17:01 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')]} 08:17:01 INFO - trying https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:17:01 INFO - Downloading https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:02 INFO - Downloaded 20259532 bytes. 08:17:02 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'] 08:17:02 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 08:17:03 INFO - Return code: 0 08:17:03 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 08:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:03 INFO - trying https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:17:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:04 INFO - Downloaded 20259532 bytes. 08:17:04 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'] 08:17:04 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 08:17:05 INFO - Return code: 0 08:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:05 INFO - trying https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:17:05 INFO - Downloading https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:06 INFO - Downloaded 14413719 bytes. 08:17:06 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'] 08:17:06 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 08:17:06 INFO - Return code: 0 08:17:06 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')]} 08:17:06 INFO - trying https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:17:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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 08:17:07 INFO - Downloaded 55613549 bytes. 08:17:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:17:07 INFO - mkdir: /builds/slave/test/properties 08:17:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:17:07 INFO - Writing to file /builds/slave/test/properties/build_url 08:17:07 INFO - Contents: 08:17:07 INFO - build_url:https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:17:08 INFO - Running post-action listener: _resource_record_post_action 08:17:08 INFO - Running post-action listener: set_extra_try_arguments 08:17:08 INFO - [mozharness: 2016-08-10 15:17:08.693057Z] Finished download-and-extract step (success) 08:17:08 INFO - [mozharness: 2016-08-10 15:17:08.693290Z] Running populate-webroot step. 08:17:08 INFO - Running pre-action listener: _resource_record_pre_action 08:17:08 INFO - Running main action method: populate_webroot 08:17:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:17:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:17:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:17:08 INFO - '--tppagecycles', 08:17:08 INFO - '7'], 08:17:08 INFO - 'tests': ['tsvgm']}}, 08:17:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:17:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:17:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:17:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:17:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:17:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:17:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['damp', 'tps']}, 08:17:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'tests': ['damp', 'tps']}, 08:17:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['dromaeo_dom']}, 08:17:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:17:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['basic_compositor_video']}, 08:17:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:17:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['a11yr', 08:17:08 INFO - 'ts_paint', 08:17:08 INFO - 'tpaint', 08:17:08 INFO - 'sessionrestore', 08:17:08 INFO - 'sessionrestore_no_auto_restore', 08:17:08 INFO - 'tabpaint']}, 08:17:08 INFO - 'other-e10s': {'tests': ['a11yr', 08:17:08 INFO - 'ts_paint', 08:17:08 INFO - 'tpaint', 08:17:08 INFO - 'sessionrestore', 08:17:08 INFO - 'sessionrestore_no_auto_restore', 08:17:08 INFO - 'tabpaint']}, 08:17:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:17:08 INFO - 'ts_paint', 08:17:08 INFO - 'tpaint', 08:17:08 INFO - 'sessionrestore', 08:17:08 INFO - 'sessionrestore_no_auto_restore', 08:17:08 INFO - 'tabpaint']}, 08:17:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:17:08 INFO - 'ts_paint', 08:17:08 INFO - 'tpaint', 08:17:08 INFO - 'sessionrestore', 08:17:08 INFO - 'sessionrestore_no_auto_restore', 08:17:08 INFO - 'tabpaint']}, 08:17:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['a11yr', 08:17:08 INFO - 'ts_paint', 08:17:08 INFO - 'tpaint', 08:17:08 INFO - 'sessionrestore', 08:17:08 INFO - 'sessionrestore_no_auto_restore', 08:17:08 INFO - 'tabpaint']}, 08:17:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['a11yr', 08:17:08 INFO - 'ts_paint', 08:17:08 INFO - 'tpaint', 08:17:08 INFO - 'sessionrestore', 08:17:08 INFO - 'sessionrestore_no_auto_restore', 08:17:08 INFO - 'tabpaint']}, 08:17:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['tsvgx', 08:17:08 INFO - 'tsvgr_opacity', 08:17:08 INFO - 'tart', 08:17:08 INFO - 'tscrollx', 08:17:08 INFO - 'cart']}, 08:17:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:17:08 INFO - 'tsvgr_opacity', 08:17:08 INFO - 'tart', 08:17:08 INFO - 'tscrollx', 08:17:08 INFO - 'cart']}, 08:17:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'talos_options': ['--disable-e10s'], 08:17:08 INFO - 'tests': ['tp5o']}, 08:17:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'tests': ['tp5o']}, 08:17:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'talos_options': ['--disable-e10s', 08:17:08 INFO - '--xperf_path', 08:17:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:17:08 INFO - 'tests': ['tp5n']}, 08:17:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:17:08 INFO - 'talos_options': ['--xperf_path', 08:17:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:17:08 INFO - 'tests': ['tp5n']}}, 08:17:08 INFO - 'talos.zip': {'path': '', 08:17:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:17:08 INFO - Running post-action listener: _resource_record_post_action 08:17:08 INFO - [mozharness: 2016-08-10 15:17:08.709531Z] Finished populate-webroot step (success) 08:17:08 INFO - [mozharness: 2016-08-10 15:17:08.709645Z] Running create-virtualenv step. 08:17:08 INFO - Running pre-action listener: _resource_record_pre_action 08:17:08 INFO - Running main action method: create_virtualenv 08:17:08 INFO - Creating virtualenv /builds/slave/test/build/venv 08:17:08 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 08:17:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:17:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:17:08 INFO - Using real prefix '/usr' 08:17:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:17:09 INFO - Installing distribute.............................................................................................................................................................................................done. 08:17:11 INFO - Installing pip.................done. 08:17:11 INFO - Return code: 0 08:17:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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', 'pip>=1.5'] in /builds/slave/test/build 08:17: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 pip>=1.5 08:17:11 INFO - Using env: {'DISPLAY': ':0', 08:17:11 INFO - 'HOME': '/home/cltbld', 08:17:11 INFO - 'LANG': 'en_US.UTF-8', 08:17:11 INFO - 'LANGUAGE': 'en_US:en', 08:17:11 INFO - 'LOGNAME': 'cltbld', 08:17:11 INFO - 'MAIL': '/var/mail/cltbld', 08:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:11 INFO - 'MOZ_NO_REMOTE': '1', 08:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:11 INFO - 'NO_EM_RESTART': '1', 08:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:11 INFO - 'PWD': '/builds/slave/test', 08:17:11 INFO - 'SHELL': '/bin/bash', 08:17:11 INFO - 'SHLVL': '1', 08:17:11 INFO - 'TERM': 'linux', 08:17:11 INFO - 'TMOUT': '86400', 08:17:11 INFO - 'USER': 'cltbld', 08:17:11 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:11 INFO - '_': '/tools/buildbot/bin/python'} 08:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:11 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 08:17:11 INFO - Cleaning up... 08:17:11 INFO - Return code: 0 08:17:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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', '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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:17: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 psutil>=3.1.1 08:17:11 INFO - Using env: {'DISPLAY': ':0', 08:17:11 INFO - 'HOME': '/home/cltbld', 08:17:11 INFO - 'LANG': 'en_US.UTF-8', 08:17:11 INFO - 'LANGUAGE': 'en_US:en', 08:17:11 INFO - 'LOGNAME': 'cltbld', 08:17:11 INFO - 'MAIL': '/var/mail/cltbld', 08:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:11 INFO - 'MOZ_NO_REMOTE': '1', 08:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:11 INFO - 'NO_EM_RESTART': '1', 08:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:11 INFO - 'PWD': '/builds/slave/test', 08:17:11 INFO - 'SHELL': '/bin/bash', 08:17:11 INFO - 'SHLVL': '1', 08:17:11 INFO - 'TERM': 'linux', 08:17:11 INFO - 'TMOUT': '86400', 08:17:11 INFO - 'USER': 'cltbld', 08:17:11 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:11 INFO - '_': '/tools/buildbot/bin/python'} 08:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:11 INFO - Downloading/unpacking psutil>=3.1.1 08:17:11 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 08:17:11 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 08:17:11 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 08:17:11 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 08:17:11 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 08:17:11 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 08:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:17:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:17:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:17:13 INFO - Installing collected packages: psutil 08:17:13 INFO - Running setup.py install for psutil 08:17:13 INFO - building 'psutil._psutil_linux' extension 08:17:13 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 08:17:13 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 08:17:13 INFO - building 'psutil._psutil_posix' extension 08:17:13 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 08:17:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:17:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:17:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:17:14 INFO - Successfully installed psutil 08:17:14 INFO - Cleaning up... 08:17:14 INFO - Return code: 0 08:17:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:14 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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17:14 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 08:17:14 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 08:17:14 INFO - Using env: {'DISPLAY': ':0', 08:17:14 INFO - 'HOME': '/home/cltbld', 08:17:14 INFO - 'LANG': 'en_US.UTF-8', 08:17:14 INFO - 'LANGUAGE': 'en_US:en', 08:17:14 INFO - 'LOGNAME': 'cltbld', 08:17:14 INFO - 'MAIL': '/var/mail/cltbld', 08:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:14 INFO - 'MOZ_NO_REMOTE': '1', 08:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:14 INFO - 'NO_EM_RESTART': '1', 08:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:14 INFO - 'PWD': '/builds/slave/test', 08:17:14 INFO - 'SHELL': '/bin/bash', 08:17:14 INFO - 'SHLVL': '1', 08:17:14 INFO - 'TERM': 'linux', 08:17:14 INFO - 'TMOUT': '86400', 08:17:14 INFO - 'USER': 'cltbld', 08:17:14 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:14 INFO - '_': '/tools/buildbot/bin/python'} 08:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:17:14 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 08:17:14 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 08:17:14 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 08:17:14 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 08:17:14 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 08:17:14 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 08:17:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:17:16 INFO - Installing collected packages: mozsystemmonitor 08:17:16 INFO - Running setup.py install for mozsystemmonitor 08:17:16 INFO - Successfully installed mozsystemmonitor 08:17:16 INFO - Cleaning up... 08:17:16 INFO - Return code: 0 08:17:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:17:16 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')]} 08:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:16 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')]} 08:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17:16 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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17:16 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 08:17:16 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 08:17:16 INFO - Using env: {'DISPLAY': ':0', 08:17:16 INFO - 'HOME': '/home/cltbld', 08:17:16 INFO - 'LANG': 'en_US.UTF-8', 08:17:16 INFO - 'LANGUAGE': 'en_US:en', 08:17:16 INFO - 'LOGNAME': 'cltbld', 08:17:16 INFO - 'MAIL': '/var/mail/cltbld', 08:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:16 INFO - 'MOZ_NO_REMOTE': '1', 08:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:16 INFO - 'NO_EM_RESTART': '1', 08:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:16 INFO - 'PWD': '/builds/slave/test', 08:17:16 INFO - 'SHELL': '/bin/bash', 08:17:16 INFO - 'SHLVL': '1', 08:17:16 INFO - 'TERM': 'linux', 08:17:16 INFO - 'TMOUT': '86400', 08:17:16 INFO - 'USER': 'cltbld', 08:17:16 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:16 INFO - '_': '/tools/buildbot/bin/python'} 08:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:17:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:17:18 INFO - warning: no files found matching '*.txt' 08:17:18 INFO - no previously-included directories found matching 'build' 08:17:18 INFO - no previously-included directories found matching 'dist' 08:17:18 INFO - no previously-included directories found matching '.git*' 08:17:18 INFO - Installing collected packages: jsonschema, functools32 08:17:19 INFO - Running setup.py install for functools32 08:17:19 INFO - warning: no files found matching '*.txt' 08:17:19 INFO - no previously-included directories found matching 'build' 08:17:19 INFO - no previously-included directories found matching 'dist' 08:17:19 INFO - no previously-included directories found matching '.git*' 08:17:19 INFO - Successfully installed jsonschema functools32 08:17:19 INFO - Cleaning up... 08:17:19 INFO - Return code: 0 08:17:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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', '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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:17: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 functools32==3.2.3-2 08:17:19 INFO - Using env: {'DISPLAY': ':0', 08:17:19 INFO - 'HOME': '/home/cltbld', 08:17:19 INFO - 'LANG': 'en_US.UTF-8', 08:17:19 INFO - 'LANGUAGE': 'en_US:en', 08:17:19 INFO - 'LOGNAME': 'cltbld', 08:17:19 INFO - 'MAIL': '/var/mail/cltbld', 08:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:19 INFO - 'MOZ_NO_REMOTE': '1', 08:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:19 INFO - 'NO_EM_RESTART': '1', 08:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:19 INFO - 'PWD': '/builds/slave/test', 08:17:19 INFO - 'SHELL': '/bin/bash', 08:17:19 INFO - 'SHLVL': '1', 08:17:19 INFO - 'TERM': 'linux', 08:17:19 INFO - 'TMOUT': '86400', 08:17:19 INFO - 'USER': 'cltbld', 08:17:19 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:19 INFO - '_': '/tools/buildbot/bin/python'} 08:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:17:19 INFO - Cleaning up... 08:17:19 INFO - Return code: 0 08:17:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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 08:17: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 08:17:19 INFO - Using env: {'DISPLAY': ':0', 08:17:19 INFO - 'HOME': '/home/cltbld', 08:17:19 INFO - 'LANG': 'en_US.UTF-8', 08:17:19 INFO - 'LANGUAGE': 'en_US:en', 08:17:19 INFO - 'LOGNAME': 'cltbld', 08:17:19 INFO - 'MAIL': '/var/mail/cltbld', 08:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:19 INFO - 'MOZ_NO_REMOTE': '1', 08:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:19 INFO - 'NO_EM_RESTART': '1', 08:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:19 INFO - 'PWD': '/builds/slave/test', 08:17:19 INFO - 'SHELL': '/bin/bash', 08:17:19 INFO - 'SHLVL': '1', 08:17:19 INFO - 'TERM': 'linux', 08:17:19 INFO - 'TMOUT': '86400', 08:17:19 INFO - 'USER': 'cltbld', 08:17:19 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:19 INFO - '_': '/tools/buildbot/bin/python'} 08:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:21 INFO - Downloading blobuploader-1.2.4.tar.gz 08:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:17:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:17:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:22 INFO - Downloading docopt-0.6.1.tar.gz 08:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:17:22 INFO - Installing collected packages: blobuploader, requests, docopt 08:17:22 INFO - Running setup.py install for blobuploader 08:17:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:17:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:17:22 INFO - Running setup.py install for requests 08:17:22 INFO - Running setup.py install for docopt 08:17:22 INFO - Successfully installed blobuploader requests docopt 08:17:22 INFO - Cleaning up... 08:17:22 INFO - Return code: 0 08:17:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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 08:17: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 08:17:22 INFO - Using env: {'DISPLAY': ':0', 08:17:22 INFO - 'HOME': '/home/cltbld', 08:17:22 INFO - 'LANG': 'en_US.UTF-8', 08:17:22 INFO - 'LANGUAGE': 'en_US:en', 08:17:22 INFO - 'LOGNAME': 'cltbld', 08:17:22 INFO - 'MAIL': '/var/mail/cltbld', 08:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:22 INFO - 'MOZ_NO_REMOTE': '1', 08:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:22 INFO - 'NO_EM_RESTART': '1', 08:17:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:22 INFO - 'PWD': '/builds/slave/test', 08:17:22 INFO - 'SHELL': '/bin/bash', 08:17:22 INFO - 'SHLVL': '1', 08:17:22 INFO - 'TERM': 'linux', 08:17:22 INFO - 'TMOUT': '86400', 08:17:22 INFO - 'USER': 'cltbld', 08:17:22 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:22 INFO - '_': '/tools/buildbot/bin/python'} 08:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:17:23 INFO - Running setup.py (path:/tmp/pip-LQNBXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:17:23 INFO - Running setup.py (path:/tmp/pip-kPDToE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:17:23 INFO - Running setup.py (path:/tmp/pip-24EhfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:17:23 INFO - Running setup.py (path:/tmp/pip-EflTSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:17:23 INFO - Running setup.py (path:/tmp/pip-Jl9rJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:17:23 INFO - Running setup.py (path:/tmp/pip-5RxwCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:17:23 INFO - Running setup.py (path:/tmp/pip-f11JYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:17:23 INFO - Running setup.py (path:/tmp/pip-bnPoOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:17:23 INFO - Running setup.py (path:/tmp/pip-8Y8x38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:17:23 INFO - Running setup.py (path:/tmp/pip-yo_mL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:17:23 INFO - Running setup.py (path:/tmp/pip-jNL6k6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:17:23 INFO - Running setup.py (path:/tmp/pip-spe1Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:17:23 INFO - Running setup.py (path:/tmp/pip-Zub2du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:17:24 INFO - Running setup.py (path:/tmp/pip-I_0tpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:24 INFO - Running setup.py (path:/tmp/pip-lKQh0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:17:24 INFO - Running setup.py (path:/tmp/pip-v6gd_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:17:24 INFO - Running setup.py (path:/tmp/pip-zB1LtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:17:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:17:24 INFO - Running setup.py install for manifestparser 08:17:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:17:24 INFO - Running setup.py install for mozcrash 08:17:24 INFO - Running setup.py install for mozdebug 08:17:24 INFO - Running setup.py install for mozdevice 08:17:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:17:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:17:24 INFO - Running setup.py install for mozfile 08:17:24 INFO - Running setup.py install for mozhttpd 08:17:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:17:24 INFO - Running setup.py install for mozinfo 08:17:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Running setup.py install for mozInstall 08:17:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Running setup.py install for mozleak 08:17:25 INFO - Running setup.py install for mozlog 08:17:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Running setup.py install for moznetwork 08:17:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Running setup.py install for mozprocess 08:17:25 INFO - Running setup.py install for mozprofile 08:17:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Running setup.py install for mozrunner 08:17:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:17:25 INFO - Running setup.py install for mozscreenshot 08:17:25 INFO - Running setup.py install for moztest 08:17:26 INFO - Running setup.py install for mozversion 08:17:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:17:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:17:26 INFO - Cleaning up... 08:17:26 INFO - Return code: 0 08:17:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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 08:17: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 08:17:26 INFO - Using env: {'DISPLAY': ':0', 08:17:26 INFO - 'HOME': '/home/cltbld', 08:17:26 INFO - 'LANG': 'en_US.UTF-8', 08:17:26 INFO - 'LANGUAGE': 'en_US:en', 08:17:26 INFO - 'LOGNAME': 'cltbld', 08:17:26 INFO - 'MAIL': '/var/mail/cltbld', 08:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:26 INFO - 'MOZ_NO_REMOTE': '1', 08:17:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:26 INFO - 'NO_EM_RESTART': '1', 08:17:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:26 INFO - 'PWD': '/builds/slave/test', 08:17:26 INFO - 'SHELL': '/bin/bash', 08:17:26 INFO - 'SHLVL': '1', 08:17:26 INFO - 'TERM': 'linux', 08:17:26 INFO - 'TMOUT': '86400', 08:17:26 INFO - 'USER': 'cltbld', 08:17:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:26 INFO - '_': '/tools/buildbot/bin/python'} 08:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:17:26 INFO - Running setup.py (path:/tmp/pip-UIE320-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:17:26 INFO - Running setup.py (path:/tmp/pip-dgQTry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:17:26 INFO - Running setup.py (path:/tmp/pip-wwHHet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:17:26 INFO - Running setup.py (path:/tmp/pip-cTVhQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:17:26 INFO - Running setup.py (path:/tmp/pip-mLx6X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:17:26 INFO - Running setup.py (path:/tmp/pip-Hp5g1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:17:26 INFO - Running setup.py (path:/tmp/pip-LOotML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:17:26 INFO - Running setup.py (path:/tmp/pip-TzOm7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:17: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)) 08:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:17:26 INFO - Running setup.py (path:/tmp/pip-XD9UPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:17:27 INFO - Running setup.py (path:/tmp/pip-ACUEjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:17:27 INFO - Running setup.py (path:/tmp/pip-EHFt9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:17: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)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:17:27 INFO - Running setup.py (path:/tmp/pip-Yi1OAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:17: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)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:17:27 INFO - Running setup.py (path:/tmp/pip-luja6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:17: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)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:17:27 INFO - Running setup.py (path:/tmp/pip-Zuvv8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:17: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)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:17:27 INFO - Running setup.py (path:/tmp/pip-_UOE6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:17: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)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:17:27 INFO - Running setup.py (path:/tmp/pip-TI7718-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:17: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)) 08:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:17:27 INFO - Running setup.py (path:/tmp/pip-UXvwqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17: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 08:17:29 INFO - Downloading blessings-1.6.tar.gz 08:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:17:29 INFO - Installing collected packages: blessings 08:17:29 INFO - Running setup.py install for blessings 08:17:29 INFO - Successfully installed blessings 08:17:29 INFO - Cleaning up... 08:17:29 INFO - Return code: 0 08:17:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:17:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:17:29 INFO - Reading from file tmpfile_stdout 08:17:29 INFO - Current package versions: 08:17:29 INFO - argparse == 1.2.1 08:17:29 INFO - blessings == 1.6 08:17:29 INFO - blobuploader == 1.2.4 08:17:29 INFO - docopt == 0.6.1 08:17:29 INFO - functools32 == 3.2.3-2 08:17:29 INFO - jsonschema == 2.5.1 08:17:29 INFO - manifestparser == 1.1 08:17:29 INFO - mozInstall == 1.12 08:17:29 INFO - mozcrash == 0.17 08:17:29 INFO - mozdebug == 0.1 08:17:29 INFO - mozdevice == 0.48 08:17:29 INFO - mozfile == 1.2 08:17:29 INFO - mozhttpd == 0.7 08:17:29 INFO - mozinfo == 0.9 08:17:29 INFO - mozleak == 0.1 08:17:29 INFO - mozlog == 3.3 08:17:29 INFO - moznetwork == 0.27 08:17:29 INFO - mozprocess == 0.23 08:17:29 INFO - mozprofile == 0.28 08:17:29 INFO - mozrunner == 6.12 08:17:29 INFO - mozscreenshot == 0.1 08:17:29 INFO - mozsystemmonitor == 0.3 08:17:29 INFO - moztest == 0.7 08:17:29 INFO - mozversion == 1.4 08:17:29 INFO - psutil == 3.1.1 08:17:29 INFO - requests == 1.2.3 08:17:29 INFO - wsgiref == 0.1.2 08:17:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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 08:17: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 08:17:29 INFO - Using env: {'DISPLAY': ':0', 08:17:29 INFO - 'HOME': '/home/cltbld', 08:17:29 INFO - 'LANG': 'en_US.UTF-8', 08:17:29 INFO - 'LANGUAGE': 'en_US:en', 08:17:29 INFO - 'LOGNAME': 'cltbld', 08:17:29 INFO - 'MAIL': '/var/mail/cltbld', 08:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:29 INFO - 'MOZ_NO_REMOTE': '1', 08:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:29 INFO - 'NO_EM_RESTART': '1', 08:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:29 INFO - 'PWD': '/builds/slave/test', 08:17:29 INFO - 'SHELL': '/bin/bash', 08:17:29 INFO - 'SHLVL': '1', 08:17:29 INFO - 'TERM': 'linux', 08:17:29 INFO - 'TMOUT': '86400', 08:17:29 INFO - 'USER': 'cltbld', 08:17:29 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:29 INFO - '_': '/tools/buildbot/bin/python'} 08:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17: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)) 08:17:30 INFO - Cleaning up... 08:17:30 INFO - Return code: 0 08:17:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:17: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')]} 08:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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')]} 08:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:17: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 0x286f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29d28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b30180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, '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': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', '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 08:17: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 08:17: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 08:17:30 INFO - Using env: {'DISPLAY': ':0', 08:17:30 INFO - 'HOME': '/home/cltbld', 08:17:30 INFO - 'LANG': 'en_US.UTF-8', 08:17:30 INFO - 'LANGUAGE': 'en_US:en', 08:17:30 INFO - 'LOGNAME': 'cltbld', 08:17:30 INFO - 'MAIL': '/var/mail/cltbld', 08:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:30 INFO - 'MOZ_NO_REMOTE': '1', 08:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:30 INFO - 'NO_EM_RESTART': '1', 08:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:30 INFO - 'PWD': '/builds/slave/test', 08:17:30 INFO - 'SHELL': '/bin/bash', 08:17:30 INFO - 'SHLVL': '1', 08:17:30 INFO - 'TERM': 'linux', 08:17:30 INFO - 'TMOUT': '86400', 08:17:30 INFO - 'USER': 'cltbld', 08:17:30 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:30 INFO - '_': '/tools/buildbot/bin/python'} 08:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:17:30 INFO - Cleaning up... 08:17:30 INFO - Return code: 0 08:17:30 INFO - Running post-action listener: _resource_record_post_action 08:17:30 INFO - Running post-action listener: _start_resource_monitoring 08:17:30 INFO - Starting resource monitoring. 08:17:30 INFO - [mozharness: 2016-08-10 15:17:30.418975Z] Finished create-virtualenv step (success) 08:17:30 INFO - [mozharness: 2016-08-10 15:17:30.419510Z] Running install step. 08:17:30 INFO - Running pre-action listener: _resource_record_pre_action 08:17:30 INFO - Running main action method: install 08:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:17:30 INFO - Reading from file tmpfile_stdout 08:17:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:17:30 INFO - Reading from file tmpfile_stdout 08:17:30 INFO - Output received: 08:17:30 INFO - Usage: mozinstall [options] installer 08:17:30 INFO - Options: 08:17:30 INFO - -h, --help show this help message and exit 08:17:30 INFO - -d DEST, --destination=DEST 08:17:30 INFO - Directory to install application into. [default: 08:17:30 INFO - "/builds/slave/test"] 08:17:30 INFO - --app=APP Application being installed. [default: firefox] 08:17:30 INFO - mkdir: /builds/slave/test/build/application 08:17: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'] 08:17: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 08:17:46 INFO - Reading from file tmpfile_stdout 08:17:46 INFO - Output received: 08:17:46 INFO - /builds/slave/test/build/application/firefox/firefox 08:17:46 INFO - Running post-action listener: _resource_record_post_action 08:17:46 INFO - [mozharness: 2016-08-10 15:17:46.791037Z] Finished install step (success) 08:17:46 INFO - [mozharness: 2016-08-10 15:17:46.791289Z] Running run-tests step. 08:17:46 INFO - Running pre-action listener: _resource_record_pre_action 08:17:46 INFO - Running main action method: run_tests 08:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:17:46 INFO - Python 2.7.3 08:17:46 INFO - Return code: 0 08:17:46 INFO - grabbing minidump binary from tooltool 08:17:46 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')]} 08:17:46 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 0x2b2e3b0>, '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 0x2b2f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:17:46 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 08:17:46 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 08:17:46 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 08:17:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:17:46 INFO - Return code: 0 08:17:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:17:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:17:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:17:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:17:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 08:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 08:17:46 INFO - Using env: {'DISPLAY': ':0', 08:17:46 INFO - 'HOME': '/home/cltbld', 08:17:46 INFO - 'LANG': 'en_US.UTF-8', 08:17:46 INFO - 'LANGUAGE': 'en_US:en', 08:17:46 INFO - 'LOGNAME': 'cltbld', 08:17:46 INFO - 'MAIL': '/var/mail/cltbld', 08:17:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:17:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:46 INFO - 'MOZ_NO_REMOTE': '1', 08:17:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:17:46 INFO - 'NO_EM_RESTART': '1', 08:17:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:46 INFO - 'PWD': '/builds/slave/test', 08:17:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:17:46 INFO - 'SHELL': '/bin/bash', 08:17:46 INFO - 'SHLVL': '1', 08:17:46 INFO - 'TERM': 'linux', 08:17:46 INFO - 'TMOUT': '86400', 08:17:46 INFO - 'USER': 'cltbld', 08:17:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1470842218.891614-179384487', 08:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:46 INFO - '_': '/tools/buildbot/bin/python'} 08:17:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 08:17:47 INFO - mozversion application_buildid: 20160810065516 08:17:47 INFO - mozversion application_changeset: c12bb83ad278a7884af9c70c3622dc6b4c819eaf 08:17:47 INFO - mozversion application_display_name: Nightly 08:17:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:17:47 INFO - mozversion application_name: Firefox 08:17:47 INFO - mozversion application_remotingname: firefox 08:17:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:17:47 INFO - mozversion application_vendor: Mozilla 08:17:47 INFO - mozversion application_version: 51.0a1 08:17:47 INFO - mozversion platform_buildid: 20160810065516 08:17:47 INFO - mozversion platform_changeset: c12bb83ad278a7884af9c70c3622dc6b4c819eaf 08:17:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:17:47 INFO - mozversion platform_version: 51.0a1 08:17:47 INFO - using testdate: 1470842267 08:17:47 INFO - actual date: 1470842267 08:17:47 INFO - starting webserver on 'localhost:58797' 08:17:47 INFO - SUITE-START | Running 2 tests 08:17:47 INFO - TEST-START | dromaeo_css 08:17:47 INFO - operating with platform_type : linux_ 08:17:47 INFO - Initialising browser for dromaeo_css test... 08:17:47 INFO - TEST-INFO | started process 5527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvQnOUc/profile http://localhost:58797/getInfo.html) 08:17:51 INFO - PROCESS | 5527 | __metrics Screen width/height:1600/1200 08:17:51 INFO - PROCESS | 5527 | colorDepth:24 08:17:51 INFO - PROCESS | 5527 | Browser inner width/height: 1024/697 08:17:51 INFO - PROCESS | 5527 | __metrics 08:17:52 INFO - PROCESS | 5527 | 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" 08:17:52 INFO - PROCESS | 5527 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:17:53 INFO - TEST-INFO | 5527: exit 0 08:17:53 INFO - Browser initialized. 08:17:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:17:53 INFO - TEST-INFO | started process 5606 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvQnOUc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:18:05 INFO - PROCESS | 5606 | RSS: Main: 157282304 08:18:05 INFO - PROCESS | 5606 | 08:18:57 INFO - PROCESS | 5606 | 1470842337281 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] 08:21:09 INFO - PROCESS | 5606 | Cycle 1(1): loaded http://localhost:58797/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58797/tests/dromaeo/cssquery-ext.html) 08:21:10 INFO - PROCESS | 5606 | RSS: Main: 276619264 08:21:10 INFO - PROCESS | 5606 | 08:24:13 INFO - PROCESS | 5606 | Cycle 1(1): loaded http://localhost:58797/tests/dromaeo/cssquery-ext.html (next: http://localhost:58797/tests/dromaeo/cssquery-jquery.html) 08:24:14 INFO - PROCESS | 5606 | RSS: Main: 245870592 08:24:14 INFO - PROCESS | 5606 | 08:27:16 INFO - PROCESS | 5606 | Cycle 1(1): loaded http://localhost:58797/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58797/tests/dromaeo/cssquery-mootools.html) 08:27:16 INFO - PROCESS | 5606 | RSS: Main: 228311040 08:27:16 INFO - PROCESS | 5606 | 08:30:19 INFO - PROCESS | 5606 | Cycle 1(1): loaded http://localhost:58797/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58797/tests/dromaeo/cssquery-prototype.html) 08:30:19 INFO - PROCESS | 5606 | RSS: Main: 198238208 08:30:19 INFO - PROCESS | 5606 | 08:33:22 INFO - PROCESS | 5606 | Cycle 1(1): loaded http://localhost:58797/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58797/tests/dromaeo/cssquery-yui.html) 08:33:23 INFO - PROCESS | 5606 | RSS: Main: 188223488 08:33:23 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | Cycle 1(1): loaded http://localhost:58797/tests/dromaeo/cssquery-yui.html (next: http://localhost:58797/tests/dromaeo/cssquery-dojo.html) 08:36:26 INFO - PROCESS | 5606 | RSS: Main: 279195648 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | __start_tp_report 08:36:26 INFO - PROCESS | 5606 | _x_x_mozilla_page_load 08:36:26 INFO - PROCESS | 5606 | _x_x_mozilla_page_load_details 08:36:26 INFO - PROCESS | 5606 | |i|pagename|runs| 08:36:26 INFO - PROCESS | 5606 | |0;dojo.html;2579.6388505609216;2619.227327938258;2648.364392545788;2670.959935600966;2673.157955244099;4576.473705523866;4631.652626053043;4721.150192965265;4745.501722319154;4759.405074365704;4561.635069194464;4636.860894173172;4686.71879687219;4690.4371475422995;4748.121597504455;4169.979150104253;4246.426732391134;4273.102648443831;4316.309390497521;4461.196984542781;4386.100849325897;4435.135148646015;4459.888502787424;4480.0367920897;4483.39474170986;4210.115875666113;4220.936685949713;4270.636995855325;4285.871423857289;4294.720843145185;4248.277792775236;4262.829486820555;4280.614744672963;4355.412019377375;4392.868213953587;4240.851570195028;4251.638610718092;4286.892827679302;4317.15815415994;4321.805518751664;4738.549837765441;4752.881177970544;4776.689515181537;4777.116233496779;4783.760811959388;4890.119778439879;4894.070126675956;4979.701217926936;4998.2002879539095;5091.159958606862;5021.57316628087;5078.771455284485;5085;5086.237064440326;5087.414947281036;4686.578207961265;4734;4736.052789442126;4744.169770290186;4745.762711864393;4982.327385802942;5044.2685810558;5143.27994080829;5191.506806853343;5215.400228973641;5015.974920125449;5017.949820501822;5021.799128034911;5037.874053148701;5077.720725360066;5105.540501354896;5139.049275883974;5156.278121063054;5157.63896527247;5159.432462429129;4810.831620893252;4882.170031094723;4937.136001199776;4943;5046.268291097844;4961.8511444656715;4968.72672003036;4980.352554168007;4988.251762235621;4992.301077849104;4234.322508398703;4235.597618226264;4251.44731184944;4300.290452075373;4305.203537345601;3696.9815150924073;3698.6301369863336;3724.404095344732;3728.6084961079237;3787.772733635991;4313.072689371737;4317.330813723878;4340.218760623118;4398.538153493837;4400.581944715247;5077.26379674945;5084.822031228963;5104;5106.540411362995;5141.717205553656;4302.934591396147;4309.655227581786;4359.17175736609;4392.956070439254;4397.450318710161;4423.933640995388;4514.052049069732;4546.340780586796;4560.498345182027;4563.06457176278;4758.690685105457;4765.213739733044;4766.213574760126;4844.588210002189;4855.223164293626;5039.193729003342;5076.923846142237;5104.84685459422;5106.234064890296;5111.821086261963;4644.047970166175;4715.929261061018;4758.428988521399;4788.090262850047;4855.101806165871;4990.675606085593;4999.975000125121;5006.474320196473;5035;5049.924251136163;5054;5070.923936140887;5071.492850714899;5073.7716802743225;5111.769970351269;4737.28940658904;4886.364772579542;4894;4918.508149185053;4957.35554377943;4903.31353610502;4953.72754498506;4960.107180707508;4977.925331119964;4992.226204938241;4881;4925.605951523941;4928.334674818853;4933.407991040955;4945.901081978413;4721.622270218443;4933.062718083553;4951.5048495150195;4963.602911767122;5018.924716129188;4912;4913.582345500529;4914.189158788759;4925.236588328815;4941.209406494943 08:36:26 INFO - PROCESS | 5606 | |1;ext.html;553.5073784331944;562.5527705474148;572.8625129968809;581.8429024163476;584.0363400389359;28386.432271354588;38787.418188727155;40009;40015.399769003416;44163.77918110413;38721;39160.21679566407;39166.21667566647;39171;39187.80406097965;7658.88511672324;7786.3381612563;7797.844043119134;7800;7804.882926756103;7433.442491813109;9836.3606365586;10058.49707514625;10082.89917100827;10377.792444151113;9969.950150249275;10057.346272492301;10073.546690398913;10095.949520252425;10128.189744820433;9871.802563948717;9934.254930880214;9945.701628951143;9962.70111896637;9963.352382095141;9928.80142397148;9987.151092157175;9992.550335234859;9998.95000525;9999.200063994864;37945.62054379449;38234.617653823385;38289.425658615146;38301.61698383036;38307.61692383068;3023.8034527755626;3079.59965204522;3195.9680403195903;3301.900942971715;3340.365330587205;29050.27374315638;29579.408411831642;29837;29881.850590747126;29899;3175.666555011737;3287.4904389819617;3378.3074469733647;3524.929501409983;3525.5593050868642;270511;272023;273262;273364;273819;31379.68620313813;32500.349993000007;53728;53951.14439700446;53964.460355395946;33169.83415082909;33227.83386083102;33265.66734332674;33308.333833323195;33445.665543344745;12380.690482737824;12456;12471;12471.064670149775;12505.624831255076;9468.763280918032;10321.122704570196;10606.787864242826;10692.09117225029;10713.89286107129;7642.617869106522;7718.884216736754;7792.688292468238;7807.219278072147;7807.765767026998;3041.695830416985;3115.7663175261946;3117.0960421477516;3132.7023932726356;3149.984250078735;828.6892415344246;862.2713806833216;901.2789768185426;937.1378331934636;938.1275413865035;25752.484950300888;26038.86980565085;27026.864865675547;27143;27483.587746183824;954.4368822394821;961.8797650293714;982.8673129127619;1004.1715584642699;1028.1671845804922;14048.350341821024;14065.43738250479;14235.145891246559;14340.354684039243;14589.270536473134;370297;371608;372007;374286;378025;47929.76035119802;48192.759036204596;48258.5174148254;48277.75861120742;48343.27485087656;1031.8710161229847;1093.2128867215592;1131.1685910855679;1190.6725650446197;1190.7023244188954;24930.878110485435;28970.13089607315;29469.705302946695;29513.409731805674;29530.704692952797;41517.79241103775;41759.582404176785;41918.99999999878;41925.742227733215;42002.579974201086;293276;293527;293690;294156;294312;23724.169654062025;24389.26832195037;24424.511509770062;24578.50842983166;24599.262022139363;39447.605523944396;39945.60054399419;40533.79733101316;40993.385099224135;41983;33136.33727325488;35856;36412;36419.817900910326;37158;35036.64963350334;35139.29721405609;35255.29489410249;35334.29331413409;35682.46476302806;33728.3254334907;34187;34869.47695784616;34963.82518087495;35046.82476587601;29678.258043549085;32560.34879302353;32601.510977335834;32635.510467342534;32688.83655581707;24210;24794.876025619757;25093.372665683502;25206.052160063562;25320.873395632905 08:36:26 INFO - PROCESS | 5606 | |2;jquery.html;328.16645719871525;344.6364085889386;353.73646633258227;354.9272399158172;372.30936612583656;12767.936160319197;16129.596760080989;16213.756793648105;16866.24101915414;17897.105144742774;15120.546383608482;15152.696946061073;15293.464728734496;15361.846381536181;15467;4840.419149702041;5055.191169412895;5064.442911279756;5122.769475373599;5303.7082960437165;3426.7943923364555;5316.441773613773;5343.332083489564;5357.7589008494715;5358.303420555322;5130.563902068328;5381.273528073699;5417.241586177938;5458.590605704569;5479.561635069204;5236.607254455931;5385.353757549119;5428.83713488596;5434.2935418395755;5435.347758269013;5183.481651834824;5255.816046438356;5258.579313654898;5262.815801446931;5267.104592219293;12337.074719396081;12365;12440.626781196579;12568.874311256863;12669.809952850715;1852.3331600623765;1882.0213488985787;1893.479293194369;1954.8142926421967;1999.0804230054193;8664.22022018015;8872.822543549157;8933.776655583597;9179.908200917973;9283.210927071206;1717.0813614716067;1723.9482815515555;1727.3263427263378;1730.8528775054135;1787.2225581871928;65738.3426165737;69498;69946.30053699449;70033;71023;10031.69904902854;10201;10223.335483193718;10307.793844123076;10450.68647940563;14067.929660351632;14068.507602233873;14068.648283792987;14072.859271407155;14077.648058798408;7115.679794409264;7366.447516436181;7531.133919599207;7616.8476630467085;7630.923690763021;7083.150021997393;7099.503034787618;7258.49190556656;7282.3809976152625;7313.670884810196;4336.848210312624;4437.800298986554;4442.222611043055;4516.593506584423;4523.258545460021;1735.9132043397778;1742.7037403641157;1779.3238569343566;1811.447508509917;1854.2953677602425;863.3611127765408;868.7176667583061;910.9453432794054;915.4187091197138;915.6017132547363;16575.336986520404;16705.248263828158;17310.134493275287;17414;17686.469405917844;870.0337237842522;870.8214815962713;876.369014309696;879.0374539878823;879.9660399031113;9346;9726.805463890823;9864.901350986542;9878.407295562289;9973.301868869255;393976;400835;419793;421630;421937;46108.769456153175;78015.60992195003;79327.60336198282;80320;80711;865.2502770149902;877.7630039889194;890.5235698901057;894.3471265975874;902.359324879343;15105.54683359501;15246.923765381101;15604.687906241585;15933.840661593236;15996.680066398374;32210;36848.63151368559;37536.812315938245;38012;39205.60794392019;52019.73990130025;52486;52800.24797768219;53452.46547534475;53979.46020539902;7601.3918886487845;7918.733002719538;7937.32532734724;8029.759107226792;8156.143604921398;11679.76640467203;12001.099917506048;12047.819282710941;12172.695682608006;12307.815382769086;10230.23273254494;10293.794124117625;10485.213608979204;10589.629362972259;10975.890241097486;10502.4223667699;10579.735506612396;10581.894181058398;10601.575936962436;10629.149668026384;10594.523246454082;10636.30863993838;10707;10731.087857532197;10865.293755905841;6496.155499784998;6862.382385585322;7023.719051237893;7181.425485961006;8162.673493060449;8860.512671803113;10282.383057016603;10383.740406489898;10485.9475702621;10827.350358978487 08:36:26 INFO - PROCESS | 5606 | |3;mootools.html;1263.949442022319;1369.137443410651;1429.556837380412;1495.970080598388;1699.660067986403;6969.965150174248;7709.113451953027;7974.920250797498;8089.029316482016;8274.172582741723;8077.474964127327;8127.471714338564;8147.918520814805;8151.551664658441;8158.347332213423;2176.6843807647883;2200.3508964855405;2201.636729939566;2201.9119235230573;2212.358416059349;2131.7548481924546;2188.059134572133;2221.1226565506613;2223.8220942324656;2226.7661895500983;2166.945826354338;2178.945526361838;2179.171914672422;2188.627933251343;2192.8903554822273;2122.1829595605727;2124.2671278408925;2127.744670639533;2130.009545561306;2134.4877229464973;2054.558269971963;2116.6930795034787;2120.014193400061;2120.151939224307;2125.7130287411155;8030.478018928809;8173.223543763333;8190.2628763410985;8200.097989221182;8202.630881610341;4456.532064133252;4642;4647.1402790483535;4674.976625116853;4675.649326300507;2844.9431011379656;2987.3129180288656;3041.391721655678;3053.5724998500227;3060.5103183490533;921.2353746390547;938.7559234598986;940.3229674634321;952.6808519146094;975.878015248094;10962;11061.446927653586;11070.778584428428;11079.446027698583;11099.55601775928;3955.564887862333;3986.1828325193264;4113.691473139466;4136.524299705572;4138.213739389506;8862.42394244372;8877.20105190536;8883.067277935854;8884.955575222211;8890.688825891191;4541.227991241497;4548.840790572314;4551.38556294896;4553.180427523078;4556.2937744649635;3547.7693949893173;4058.6550143237564;4187.413762073312;4243.702940794176;4247.086876321606;3385.966140338565;3394.2023624448234;3496.7727097738566;3511.1046683095647;3513.5959364672876;956.6221342569785;970.8398114311201;974.4737841565475;980.632262901412;982.9115379615869;779.8089468080357;810.0117856215408;814.0434988888032;814.3932770086324;816.7345612675786;5116.744162791845;5157.071727089085;5267.631265811357;5273;5394.78420863161;373.32800958275;385.5970510816204;389.5889836222812;390.7811625489678;396.2411981056245;3584.551931008624;3592.6586974237407;3593.2274560968995;3639.3085313790293;3680.871169509054;9800;10194.388336699822;10253.794924101476;10270.229732770073;10270.6405275815;10211.897881021243;10213.182945364353;10217;10217.846732299022;10228.744281392876;7558.093028836574;7566.886496702554;7607.35337496319;7675.616219189018;7692.999910011663;5270.183121616155;5278.155495120763;5373.811916582901;5495.450454954473;5498.890022199454;9046.095390460901;9612.327137100336;9614.230861530921;9616.663416780659;9623.5669394876;9063.13900179482;9485.098915603006;9593.568289426852;9656.034396560288;9683.757906052406;6110.633361998294;6180.412860778219;6932.133483314586;7070.893936591058;7082.752103676346;7453.627318633939;7679.232076792276;7690.961545192238;7706.576138312446;7706.7687969361;6623.735050597922;6780.69486873082;6806.489513286622;6943;7041.823954400976;6773.085633439619;6847.452203823583;6864.965675171592;6912.585244885323;6940;6230.283517395557;6325.335839736761;6327.430531252209;6333;6340.841478963062;4537.705049171794;4554.36238926544;4604.21728306182;4710.81156753726;4792.0895029944195;4596.563326483979;4598.149342371672;4615.192341340185;4692.41344831896;4854.635902307412 08:36:26 INFO - PROCESS | 5606 | |4;prototype.html;268.9515887140315;274.83509894063565;281.99999999999994;284.78822609795316;284.8860455817673;4880.145974454474;4888.460135057455;4898.044881248149;4907.779149938248;4912.754362281889;4519.254323036703;4678.5789278964885;4709.529047095289;4711.128441238365;4816.397950256218;3602.7297952653525;3648.781073135607;3675.779453232801;3710.257948410329;3738.5139931808963;3724.720645951551;3742.2328422673286;3748;3792.9810350948346;3799.183175617242;3636.909077273076;3721.255748850227;3744.4008958566633;3746.3443897317998;3758.2295629396044;3721.3673675475097;3745.793981331026;3748.6063963283736;3749.2378582066385;3763.360228761117;3279.3441311737747;3280.2947366316357;3280.6883346082086;3290.3090351026312;3294.884679036222;4760.33355330254;4761.833335833229;4768.928466073012;4772.830656489147;4783.3064205690325;4155.542890282067;4190.476190476191;4240.406343111967;4249.405083288342;4286.357046443059;4954.133026720309;4973.278874563168;4982.77577509013;4986.4265609455015;5019.397672279313;3533.452314891196;3551.5738111426795;3557.7763897564023;3564.411872041136;3594.19130695594;4984.700917944899;5261.158214685632;5328.680279183222;5341.545968592674;5348.732563371816;3534.876279330211;3544.627814079536;3557.6620221079475;3573.4282514797505;3577.821108944542;3602.9819850900576;3622.5471816022996;3630.419132938717;3631.3100510902846;3637.5998640149564;4184.14225083857;4198.9370159447635;4206.6003729646245;4212.473440819897;4216.599423054805;4878.731669758126;4971.6271279654175;5035.546800787946;5044.218146187347;5083.847484575469;1235.7281398092405;1249.2816630437535;1251.9546178940504;1252.630474010169;1252.7431876465303;779.3453499060817;789.2344425906862;823.1192623892377;832.4422636833336;848.9278411335106;4405.867823965286;4413.536578659258;4442.666799990014;4464;4491.416115904912;5177.7152256625495;5183.326167598264;5190.558802501754;5216.791328346823;5229.529342359206;4250.872473825791;4449.599536041707;4483.349914262414;4492.101579684077;4499.100179963955;2738.945221095607;2747.052266967893;2748.9725102748716;2753.518134326485;2753.655793025872;4822.300766388854;4830;4868.294097355934;4871.391076115486;4879.048585525859;5071.8985620287385;5173.689578625294;5242.633015688865;5244.187150991603;5249.448807875195;4817.542333478314;4841.3222148899185;4854.344663470528;4855.150348689036;4876.146674331978;5114.181730923035;5128.743352924701;5139.408967968731;5148.279240906201;5175.774417000725;4781.258904869751;5010.974945125251;5025.3718285214345;5032.949670503248;5059.873503162303;4842.588379987599;4869.269609558594;4875.463698993037;4889.7799599018845;4894.755262236945;3536.6463353664426;3546.3971124909344;3553.840077196584;3558.6619271169197;3569.464580312974;4921.8523444296725;5144.948550514597;5152.381714194321;5154.381474223117;5197.324347834758;4977.352944117241;5068.417132029864;5069.847904562869;5096.617753668415;5098;5035.496450355082;5047.899042019066;5054.772535235849;5056.924146137737;5064.82273120439;4815.807367705253;4963.751812409438;5023.2716256143385;5061.620378471555;5067.13858644024;4837.830675926326;4960.454350021567;5118.334616499831;5125.205593133071;5134.409542902614;5022.296878436949;5033.094043072282;5061.392632884077;5065.493450654905;5081.898362032813 08:36:26 INFO - PROCESS | 5606 | |5;yui.html;625.6402568523099;708.6385943168979;719.5574721546251;731.6524650790873;734.1887214627833;12970.870291297084;13596.388162532685;13636.250006249653;13768.86231137687;14289.642758931019;12718.745625087493;12789.872101279008;12794.424250908709;12798.744025119515;12799.872001280008;613.7054213977293;740.3595889555536;741.8961345411647;766.5898744171869;768.6387397922967;770.7841804294807;771.1863983497408;771.8456308738247;773.9187385324544;774.020863607537;809.2756982500688;812.155358427238;812.9227723366271;813.3330668851544;813.6175997281268;650.6811662285494;785.3913217256614;807.3702512040618;812.1391325195311;812.5977641067709;587.967661778602;590.852286928268;592.0556712044282;592.1916583863039;593.1843714892024;13024.41390137446;13037.348132593332;13043.86956130436;13047.282399468026;13076.411561479756;12459.813102803466;12751.234925904475;12949.611511654666;13044.739105217843;13239.801402978963;11919.702007449796;12234.755304893852;12238.388080595934;12289.815652765215;12527.874721252852;4073.388991651246;4090.141070375225;4155.21051000312;4158.126793373365;4192.4759405074365;64785;64848.67575662091;64887;64922.350776492574;64959;9796.118349348593;10040.748981275527;10325.793484130276;10352.5341359639;10658.893411065945;18980.43058708241;19153.904230478758;19290.324838630862;19331.51671208231;19334.129964151365;6553.770618028441;6556.475481961527;6583.30875258091;6623.172103487064;6863;3724.925501490009;3781.8865434037025;3797.582265950743;3818.59904710007;3833.3291673956946;2744.917652470429;2845.8007939444456;2846.957295640567;2852.1871266688895;2853.9001134960176;443.4744827379566;486.7882471125072;487.24963556123174;495.6010411618682;499.2910067703869;540.9026375252414;585.4906231578553;590.2592246730326;628.6887990444759;635.190132580963;8309.127541608044;8608.48349099056;8637.222649961534;8771.429857059271;8953;491.86965454154677;521.4681025354193;575.7696921231541;612.7181496511565;616.4236438929615;4030.455888455021;4050.9594904051173;4556.407667003268;4647;4907.28357851449;99009.50495247477;102778;102975.48512257391;105592;107589.4620526908;68082;82392;83157.58421207855;85645;88330.55834720786;74216.62891685507;75208.62395688095;76023.61988190023;76463.61768191123;76734.23265767383;7905.683772649029;8436.957815210884;8454.196851299117;8599.097094804956;8664.35017373687;44272.778636107905;45631;45704.77147614374;45765.31352029789;45978.31032534582;58102.4189758097;64585;68134.20102377032;68396;68436;11441.771164576829;11471.230158730159;11848;11892.345920974429;11916.344601047025;13002.544910928451;13434.193948363129;13452.327383630975;13554.186748795104;13865.445382185004;11204.103671706407;11436.142289328167;11636.243644163102;11698.649040528799;12065.939670301592;12419.565315213924;12419.565315213924;12675.30285834288;12688.492460301484;13017.869821301665;11419.14356423255;11501.712457188638;11889.28664280146;11890;12331.630051098482;9679.370840895319;9750;9900.801983960137;10300.433476158883;10410.791784164427;9756.414615123116;9894.851577226204;10522.105621021992;10617.309874858109;10814.243002989715 08:36:26 INFO - PROCESS | 5606 | __end_tp_report 08:36:26 INFO - PROCESS | 5606 | __start_cc_report 08:36:26 INFO - PROCESS | 5606 | _x_x_mozilla_cycle_collect,1126 08:36:26 INFO - PROCESS | 5606 | __end_cc_report 08:36:26 INFO - PROCESS | 5606 | __startTimestamp1470843386819__endTimestamp 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | ------- Summary: start ------- 08:36:26 INFO - PROCESS | 5606 | Number of tests: 6 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | [#0] dojo.html Cycles:165 Average:4665.63 Median:4783.76 stddev:496.68 (10.4%) stddev-sans-first:470.47 08:36:26 INFO - PROCESS | 5606 | Values: 2579.6 2619.2 2648.4 2671.0 2673.2 4576.5 4631.7 4721.2 4745.5 4759.4 4561.6 4636.9 4686.7 4690.4 4748.1 4170.0 4246.4 4273.1 4316.3 4461.2 4386.1 4435.1 4459.9 4480.0 4483.4 4210.1 4220.9 4270.6 4285.9 4294.7 4248.3 4262.8 4280.6 4355.4 4392.9 4240.9 4251.6 4286.9 4317.2 4321.8 4738.5 4752.9 4776.7 4777.1 4783.8 4890.1 4894.1 4979.7 4998.2 5091.2 5021.6 5078.8 5085.0 5086.2 5087.4 4686.6 4734.0 4736.1 4744.2 4745.8 4982.3 5044.3 5143.3 5191.5 5215.4 5016.0 5017.9 5021.8 5037.9 5077.7 5105.5 5139.0 5156.3 5157.6 5159.4 4810.8 4882.2 4937.1 4943.0 5046.3 4961.9 4968.7 4980.4 4988.3 4992.3 4234.3 4235.6 4251.4 4300.3 4305.2 3697.0 3698.6 3724.4 3728.6 3787.8 4313.1 4317.3 4340.2 4398.5 4400.6 5077.3 5084.8 5104.0 5106.5 5141.7 4302.9 4309.7 4359.2 4393.0 4397.5 4423.9 4514.1 4546.3 4560.5 4563.1 4758.7 4765.2 4766.2 4844.6 4855.2 5039.2 5076.9 5104.8 5106.2 5111.8 4644.0 4715.9 4758.4 4788.1 4855.1 4990.7 5000.0 5006.5 5035.0 5049.9 5054.0 5070.9 5071.5 5073.8 5111.8 4737.3 4886.4 4894.0 4918.5 4957.4 4903.3 4953.7 4960.1 4977.9 4992.2 4881.0 4925.6 4928.3 4933.4 4945.9 4721.6 4933.1 4951.5 4963.6 5018.9 4912.0 4913.6 4914.2 4925.2 4941.2 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | [#1] ext.html Cycles:180 Average:45593.24 Median:18782.65 stddev:83222.66 (443.1%) stddev-sans-first:83387.42 08:36:26 INFO - PROCESS | 5606 | Values: 553.5 562.6 572.9 581.8 584.0 28386.4 38787.4 40009.0 40015.4 44163.8 38721.0 39160.2 39166.2 39171.0 39187.8 7658.9 7786.3 7797.8 7800.0 7804.9 7433.4 9836.4 10058.5 10082.9 10377.8 9970.0 10057.3 10073.5 10095.9 10128.2 9871.8 9934.3 9945.7 9962.7 9963.4 9928.8 9987.2 9992.6 9999.0 9999.2 37945.6 38234.6 38289.4 38301.6 38307.6 3023.8 3079.6 3196.0 3301.9 3340.4 29050.3 29579.4 29837.0 29881.9 29899.0 3175.7 3287.5 3378.3 3524.9 3525.6 270511.0 272023.0 273262.0 273364.0 273819.0 31379.7 32500.3 53728.0 53951.1 53964.5 33169.8 33227.8 33265.7 33308.3 33445.7 12380.7 12456.0 12471.0 12471.1 12505.6 9468.8 10321.1 10606.8 10692.1 10713.9 7642.6 7718.9 7792.7 7807.2 7807.8 3041.7 3115.8 3117.1 3132.7 3150.0 828.7 862.3 901.3 937.1 938.1 25752.5 26038.9 27026.9 27143.0 27483.6 954.4 961.9 982.9 1004.2 1028.2 14048.4 14065.4 14235.1 14340.4 14589.3 370297.0 371608.0 372007.0 374286.0 378025.0 47929.8 48192.8 48258.5 48277.8 48343.3 1031.9 1093.2 1131.2 1190.7 1190.7 24930.9 28970.1 29469.7 29513.4 29530.7 41517.8 41759.6 41919.0 41925.7 42002.6 293276.0 293527.0 293690.0 294156.0 294312.0 23724.2 24389.3 24424.5 24578.5 24599.3 39447.6 39945.6 40533.8 40993.4 41983.0 33136.3 35856.0 36412.0 36419.8 37158.0 35036.6 35139.3 35255.3 35334.3 35682.5 33728.3 34187.0 34869.5 34963.8 35046.8 29678.3 32560.3 32601.5 32635.5 32688.8 24210.0 24794.9 25093.4 25206.1 25320.9 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | [#2] jquery.html Cycles:180 Average:24602.09 Median:4387.32 stddev:67865.89 (1546.9%) stddev-sans-first:68031.80 08:36:26 INFO - PROCESS | 5606 | Values: 328.2 344.6 353.7 354.9 372.3 12767.9 16129.6 16213.8 16866.2 17897.1 15120.5 15152.7 15293.5 15361.8 15467.0 4840.4 5055.2 5064.4 5122.8 5303.7 3426.8 5316.4 5343.3 5357.8 5358.3 5130.6 5381.3 5417.2 5458.6 5479.6 5236.6 5385.4 5428.8 5434.3 5435.3 5183.5 5255.8 5258.6 5262.8 5267.1 12337.1 12365.0 12440.6 12568.9 12669.8 1852.3 1882.0 1893.5 1954.8 1999.1 8664.2 8872.8 8933.8 9179.9 9283.2 1717.1 1723.9 1727.3 1730.9 1787.2 65738.3 69498.0 69946.3 70033.0 71023.0 10031.7 10201.0 10223.3 10307.8 10450.7 14067.9 14068.5 14068.6 14072.9 14077.6 7115.7 7366.4 7531.1 7616.8 7630.9 7083.2 7099.5 7258.5 7282.4 7313.7 4336.8 4437.8 4442.2 4516.6 4523.3 1735.9 1742.7 1779.3 1811.4 1854.3 863.4 868.7 910.9 915.4 915.6 16575.3 16705.2 17310.1 17414.0 17686.5 870.0 870.8 876.4 879.0 880.0 9346.0 9726.8 9864.9 9878.4 9973.3 393976.0 400835.0 419793.0 421630.0 421937.0 46108.8 78015.6 79327.6 80320.0 80711.0 865.3 877.8 890.5 894.3 902.4 15105.5 15246.9 15604.7 15933.8 15996.7 32210.0 36848.6 37536.8 38012.0 39205.6 52019.7 52486.0 52800.2 53452.5 53979.5 7601.4 7918.7 7937.3 8029.8 8156.1 11679.8 12001.1 12047.8 12172.7 12307.8 10230.2 10293.8 10485.2 10589.6 10975.9 10502.4 10579.7 10581.9 10601.6 10629.1 10594.5 10636.3 10707.0 10731.1 10865.3 6496.2 6862.4 7023.7 7181.4 8162.7 8860.5 10282.4 10383.7 10485.9 10827.4 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | [#3] mootools.html Cycles:180 Average:5223.56 Median:4701.61 stddev:3050.30 (64.9%) stddev-sans-first:3044.34 08:36:26 INFO - PROCESS | 5606 | Values: 1263.9 1369.1 1429.6 1496.0 1699.7 6970.0 7709.1 7974.9 8089.0 8274.2 8077.5 8127.5 8147.9 8151.6 8158.3 2176.7 2200.4 2201.6 2201.9 2212.4 2131.8 2188.1 2221.1 2223.8 2226.8 2166.9 2178.9 2179.2 2188.6 2192.9 2122.2 2124.3 2127.7 2130.0 2134.5 2054.6 2116.7 2120.0 2120.2 2125.7 8030.5 8173.2 8190.3 8200.1 8202.6 4456.5 4642.0 4647.1 4675.0 4675.6 2844.9 2987.3 3041.4 3053.6 3060.5 921.2 938.8 940.3 952.7 975.9 10962.0 11061.4 11070.8 11079.4 11099.6 3955.6 3986.2 4113.7 4136.5 4138.2 8862.4 8877.2 8883.1 8885.0 8890.7 4541.2 4548.8 4551.4 4553.2 4556.3 3547.8 4058.7 4187.4 4243.7 4247.1 3386.0 3394.2 3496.8 3511.1 3513.6 956.6 970.8 974.5 980.6 982.9 779.8 810.0 814.0 814.4 816.7 5116.7 5157.1 5267.6 5273.0 5394.8 373.3 385.6 389.6 390.8 396.2 3584.6 3592.7 3593.2 3639.3 3680.9 9800.0 10194.4 10253.8 10270.2 10270.6 10211.9 10213.2 10217.0 10217.8 10228.7 7558.1 7566.9 7607.4 7675.6 7693.0 5270.2 5278.2 5373.8 5495.5 5498.9 9046.1 9612.3 9614.2 9616.7 9623.6 9063.1 9485.1 9593.6 9656.0 9683.8 6110.6 6180.4 6932.1 7070.9 7082.8 7453.6 7679.2 7691.0 7706.6 7706.8 6623.7 6780.7 6806.5 6943.0 7041.8 6773.1 6847.5 6865.0 6912.6 6940.0 6230.3 6325.3 6327.4 6333.0 6340.8 4537.7 4554.4 4604.2 4710.8 4792.1 4596.6 4598.1 4615.2 4692.4 4854.6 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | [#4] prototype.html Cycles:180 Average:4163.17 Median:4816.10 stddev:1224.64 (25.4%) stddev-sans-first:1192.69 08:36:26 INFO - PROCESS | 5606 | Values: 269.0 274.8 282.0 284.8 284.9 4880.1 4888.5 4898.0 4907.8 4912.8 4519.3 4678.6 4709.5 4711.1 4816.4 3602.7 3648.8 3675.8 3710.3 3738.5 3724.7 3742.2 3748.0 3793.0 3799.2 3636.9 3721.3 3744.4 3746.3 3758.2 3721.4 3745.8 3748.6 3749.2 3763.4 3279.3 3280.3 3280.7 3290.3 3294.9 4760.3 4761.8 4768.9 4772.8 4783.3 4155.5 4190.5 4240.4 4249.4 4286.4 4954.1 4973.3 4982.8 4986.4 5019.4 3533.5 3551.6 3557.8 3564.4 3594.2 4984.7 5261.2 5328.7 5341.5 5348.7 3534.9 3544.6 3557.7 3573.4 3577.8 3603.0 3622.5 3630.4 3631.3 3637.6 4184.1 4198.9 4206.6 4212.5 4216.6 4878.7 4971.6 5035.5 5044.2 5083.8 1235.7 1249.3 1252.0 1252.6 1252.7 779.3 789.2 823.1 832.4 848.9 4405.9 4413.5 4442.7 4464.0 4491.4 5177.7 5183.3 5190.6 5216.8 5229.5 4250.9 4449.6 4483.3 4492.1 4499.1 2738.9 2747.1 2749.0 2753.5 2753.7 4822.3 4830.0 4868.3 4871.4 4879.0 5071.9 5173.7 5242.6 5244.2 5249.4 4817.5 4841.3 4854.3 4855.2 4876.1 5114.2 5128.7 5139.4 5148.3 5175.8 4781.3 5011.0 5025.4 5032.9 5059.9 4842.6 4869.3 4875.5 4889.8 4894.8 3536.6 3546.4 3553.8 3558.7 3569.5 4921.9 5144.9 5152.4 5154.4 5197.3 4977.4 5068.4 5069.8 5096.6 5098.0 5035.5 5047.9 5054.8 5056.9 5064.8 4815.8 4963.8 5023.3 5061.6 5067.1 4837.8 4960.5 5118.3 5125.2 5134.4 5022.3 5033.1 5061.4 5065.5 5081.9 08:36:26 INFO - PROCESS | 5606 | 08:36:26 INFO - PROCESS | 5606 | [#5] yui.html Cycles:180 Average:18257.38 Median:45871.81 stddev:26043.12 (56.8%) stddev-sans-first:26082.52 08:36:26 INFO - PROCESS | 5606 | Values: 625.6 708.6 719.6 731.7 734.2 12970.9 13596.4 13636.3 13768.9 14289.6 12718.7 12789.9 12794.4 12798.7 12799.9 613.7 740.4 741.9 766.6 768.6 770.8 771.2 771.8 773.9 774.0 809.3 812.2 812.9 813.3 813.6 650.7 785.4 807.4 812.1 812.6 588.0 590.9 592.1 592.2 593.2 13024.4 13037.3 13043.9 13047.3 13076.4 12459.8 12751.2 12949.6 13044.7 13239.8 11919.7 12234.8 12238.4 12289.8 12527.9 4073.4 4090.1 4155.2 4158.1 4192.5 64785.0 64848.7 64887.0 64922.4 64959.0 9796.1 10040.7 10325.8 10352.5 10658.9 18980.4 19153.9 19290.3 19331.5 19334.1 6553.8 6556.5 6583.3 6623.2 6863.0 3724.9 3781.9 3797.6 3818.6 3833.3 2744.9 2845.8 2847.0 2852.2 2853.9 443.5 486.8 487.2 495.6 499.3 540.9 585.5 590.3 628.7 635.2 8309.1 8608.5 8637.2 8771.4 8953.0 491.9 521.5 575.8 612.7 616.4 4030.5 4051.0 4556.4 4647.0 4907.3 99009.5 102778.0 102975.5 105592.0 107589.5 68082.0 82392.0 83157.6 85645.0 88330.6 74216.6 75208.6 76023.6 76463.6 76734.2 7905.7 8437.0 8454.2 8599.1 8664.4 44272.8 45631.0 45704.8 45765.3 45978.3 58102.4 64585.0 68134.2 68396.0 68436.0 11441.8 11471.2 11848.0 11892.3 11916.3 13002.5 13434.2 13452.3 13554.2 13865.4 11204.1 11436.1 11636.2 11698.6 12065.9 12419.6 12419.6 12675.3 12688.5 13017.9 11419.1 11501.7 11889.3 11890.0 12331.6 9679.4 9750.0 9900.8 10300.4 10410.8 9756.4 9894.9 10522.1 10617.3 10814.2 08:36:26 INFO - PROCESS | 5606 | -------- Summary: end -------- 08:36:26 INFO - PROCESS | 5606 | 08:36:27 INFO - TEST-INFO | 5606: exit 0 08:36:27 INFO - TEST-OK | dromaeo_css | took 1120603ms 08:36:27 INFO - TEST-START | kraken 08:36:27 INFO - operating with platform_type : linux_ 08:36:27 INFO - Initialising browser for kraken test... 08:36:27 INFO - TEST-INFO | started process 5792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqnd1sj/profile http://localhost:58797/getInfo.html) 08:36:32 INFO - PROCESS | 5792 | __metrics Screen width/height:1600/1200 08:36:32 INFO - PROCESS | 5792 | colorDepth:24 08:36:32 INFO - PROCESS | 5792 | Browser inner width/height: 1024/697 08:36:32 INFO - PROCESS | 5792 | __metrics 08:36:33 INFO - PROCESS | 5792 | 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" 08:36:33 INFO - PROCESS | 5792 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:36:34 INFO - TEST-INFO | 5792: exit 0 08:36:34 INFO - Browser initialized. 08:36:34 INFO - Running cycle 1/1 for kraken test... 08:36:34 INFO - TEST-INFO | started process 5872 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqnd1sj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:46 INFO - PROCESS | 5872 | RSS: Main: 157245440 08:36:46 INFO - PROCESS | 5872 | 08:36:48 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58797/tests/kraken/driver.html?testName=audio-beat-detection) 08:36:49 INFO - PROCESS | 5872 | RSS: Main: 213348352 08:36:49 INFO - PROCESS | 5872 | 08:36:52 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58797/tests/kraken/driver.html?testName=audio-dft) 08:36:53 INFO - PROCESS | 5872 | RSS: Main: 192299008 08:36:53 INFO - PROCESS | 5872 | 08:36:56 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58797/tests/kraken/driver.html?testName=audio-fft) 08:36:57 INFO - PROCESS | 5872 | RSS: Main: 271507456 08:36:57 INFO - PROCESS | 5872 | 08:36:59 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58797/tests/kraken/driver.html?testName=audio-oscillator) 08:36:59 INFO - PROCESS | 5872 | RSS: Main: 180035584 08:36:59 INFO - PROCESS | 5872 | 08:37:02 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58797/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:37:02 INFO - PROCESS | 5872 | RSS: Main: 184680448 08:37:02 INFO - PROCESS | 5872 | 08:37:05 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58797/tests/kraken/driver.html?testName=imaging-darkroom) 08:37:06 INFO - PROCESS | 5872 | RSS: Main: 221220864 08:37:06 INFO - PROCESS | 5872 | 08:37:09 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58797/tests/kraken/driver.html?testName=imaging-desaturate) 08:37:10 INFO - PROCESS | 5872 | RSS: Main: 215326720 08:37:10 INFO - PROCESS | 5872 | 08:37:13 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58797/tests/kraken/driver.html?testName=json-parse-financial) 08:37:14 INFO - PROCESS | 5872 | RSS: Main: 218095616 08:37:14 INFO - PROCESS | 5872 | 08:37:15 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58797/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:37:16 INFO - PROCESS | 5872 | RSS: Main: 183914496 08:37:16 INFO - PROCESS | 5872 | 08:37:18 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:37:19 INFO - PROCESS | 5872 | RSS: Main: 188960768 08:37:19 INFO - PROCESS | 5872 | 08:37:21 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:37:21 INFO - PROCESS | 5872 | RSS: Main: 193236992 08:37:21 INFO - PROCESS | 5872 | 08:37:24 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:37:25 INFO - PROCESS | 5872 | RSS: Main: 188903424 08:37:25 INFO - PROCESS | 5872 | 08:37:28 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:37:28 INFO - PROCESS | 5872 | RSS: Main: 192098304 08:37:28 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | Cycle 1(1): loaded http://localhost:58797/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58797/tests/kraken/driver.html?testName=ai-astar) 08:37:30 INFO - PROCESS | 5872 | RSS: Main: 242950144 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | __start_tp_report 08:37:30 INFO - PROCESS | 5872 | _x_x_mozilla_page_load 08:37:30 INFO - PROCESS | 5872 | _x_x_mozilla_page_load_details 08:37:30 INFO - PROCESS | 5872 | |i|pagename|runs| 08:37:30 INFO - PROCESS | 5872 | |0;ai-astar;105;115;116;115;114;114;116;116;116;115 08:37:30 INFO - PROCESS | 5872 | |1;audio-beat-detection;149;122;124;178;123;132;154;129;179;123 08:37:30 INFO - PROCESS | 5872 | |2;audio-dft;165;162;163;161;161;163;161;163;166;162 08:37:30 INFO - PROCESS | 5872 | |3;audio-fft;76;77;76;103;77;77;76;75;115;76 08:37:30 INFO - PROCESS | 5872 | |4;audio-oscillator;85;85;126;85;88;87;120;85;85;128 08:37:30 INFO - PROCESS | 5872 | |5;imaging-gaussian-blur;118;117;116;117;117;117;117;116;116;117 08:37:30 INFO - PROCESS | 5872 | |6;imaging-darkroom;147;146;146;147;146;147;149;146;147;147 08:37:30 INFO - PROCESS | 5872 | |7;imaging-desaturate;95;95;96;95;95;95;96;95;95;94 08:37:30 INFO - PROCESS | 5872 | |8;json-parse-financial;67;66;66;64;64;66;64;63;63;63 08:37:30 INFO - PROCESS | 5872 | |9;json-stringify-tinderbox;65;66;66;67;66;65;66;66;66;66 08:37:30 INFO - PROCESS | 5872 | |10;stanford-crypto-aes;87;91;81;80;80;81;82;89;81;85 08:37:30 INFO - PROCESS | 5872 | |11;stanford-crypto-ccm;146;136;133;135;133;133;132;140;156;141 08:37:30 INFO - PROCESS | 5872 | |12;stanford-crypto-pbkdf2;177;181;172;173;177;176;169;171;172;171 08:37:30 INFO - PROCESS | 5872 | |13;stanford-crypto-sha256-iterative;70;69;68;68;74;72;70;68;68;67 08:37:30 INFO - PROCESS | 5872 | __end_tp_report 08:37:30 INFO - PROCESS | 5872 | __start_cc_report 08:37:30 INFO - PROCESS | 5872 | _x_x_mozilla_cycle_collect,1971 08:37:30 INFO - PROCESS | 5872 | __end_cc_report 08:37:30 INFO - PROCESS | 5872 | __startTimestamp1470843450952__endTimestamp 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | ------- Summary: start ------- 08:37:30 INFO - PROCESS | 5872 | Number of tests: 14 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.50 stddev:3.33 (2.9%) stddev-sans-first:0.83 08:37:30 INFO - PROCESS | 5872 | Values: 105.0 115.0 116.0 115.0 114.0 114.0 116.0 116.0 116.0 115.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:140.50 stddev:22.53 (16.0%) stddev-sans-first:23.72 08:37:30 INFO - PROCESS | 5872 | Values: 149.0 122.0 124.0 178.0 123.0 132.0 154.0 129.0 179.0 123.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.70 (1.0%) stddev-sans-first:1.59 08:37:30 INFO - PROCESS | 5872 | Values: 165.0 162.0 163.0 161.0 161.0 163.0 161.0 163.0 166.0 162.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#3] audio-fft Cycles:10 Average:82.80 Median:76.00 stddev:14.11 (18.6%) stddev-sans-first:14.75 08:37:30 INFO - PROCESS | 5872 | Values: 76.0 77.0 76.0 103.0 77.0 77.0 76.0 75.0 115.0 76.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:85.00 stddev:18.95 (22.3%) stddev-sans-first:19.56 08:37:30 INFO - PROCESS | 5872 | Values: 85.0 85.0 126.0 85.0 88.0 87.0 120.0 85.0 85.0 128.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 08:37:30 INFO - PROCESS | 5872 | Values: 118.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 08:37:30 INFO - PROCESS | 5872 | Values: 147.0 146.0 146.0 147.0 146.0 147.0 149.0 146.0 147.0 147.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:37:30 INFO - PROCESS | 5872 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:1.51 (2.3%) stddev-sans-first:1.32 08:37:30 INFO - PROCESS | 5872 | Values: 67.0 66.0 66.0 64.0 64.0 66.0 64.0 63.0 63.0 63.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 08:37:30 INFO - PROCESS | 5872 | Values: 65.0 66.0 66.0 67.0 66.0 65.0 66.0 66.0 66.0 66.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.50 stddev:4.03 (4.8%) stddev-sans-first:4.09 08:37:30 INFO - PROCESS | 5872 | Values: 87.0 91.0 81.0 80.0 80.0 81.0 82.0 89.0 81.0 85.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:138.00 stddev:7.62 (5.5%) stddev-sans-first:7.58 08:37:30 INFO - PROCESS | 5872 | Values: 146.0 136.0 133.0 135.0 133.0 133.0 132.0 140.0 156.0 141.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.50 stddev:3.70 (2.1%) stddev-sans-first:3.75 08:37:30 INFO - PROCESS | 5872 | Values: 177.0 181.0 172.0 173.0 177.0 176.0 169.0 171.0 172.0 171.0 08:37:30 INFO - PROCESS | 5872 | 08:37:30 INFO - PROCESS | 5872 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:2.17 (3.1%) stddev-sans-first:2.29 08:37:30 INFO - PROCESS | 5872 | Values: 70.0 69.0 68.0 68.0 74.0 72.0 70.0 68.0 68.0 67.0 08:37:30 INFO - PROCESS | 5872 | -------- Summary: end -------- 08:37:30 INFO - PROCESS | 5872 | 08:37:32 INFO - TEST-INFO | 5872: exit 0 08:37:32 INFO - TEST-OK | kraken | took 64438ms 08:37:32 INFO - SUITE-END | took 1185s 08:37:32 INFO - Completed test suite (00:19:45) 08:37:32 INFO - javascript benchmark 08:37:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2579.6388505609216, 2619.227327938258, 2648.364392545788, 2670.959935600966, 2673.157955244099, 4576.473705523866, 4631.652626053043, 4721.150192965265, 4745.501722319154, 4759.405074365704, 4561.635069194464, 4636.860894173172, 4686.71879687219, 4690.4371475422995, 4748.121597504455, 4169.979150104253, 4246.426732391134, 4273.102648443831, 4316.309390497521, 4461.196984542781, 4386.100849325897, 4435.135148646015, 4459.888502787424, 4480.0367920897, 4483.39474170986, 4210.115875666113, 4220.936685949713, 4270.636995855325, 4285.871423857289, 4294.720843145185, 4248.277792775236, 4262.829486820555, 4280.614744672963, 4355.412019377375, 4392.868213953587, 4240.851570195028, 4251.638610718092, 4286.892827679302, 4317.15815415994, 4321.805518751664, 4738.549837765441, 4752.881177970544, 4776.689515181537, 4777.116233496779, 4783.760811959388, 4890.119778439879, 4894.070126675956, 4979.701217926936, 4998.2002879539095, 5091.159958606862, 5021.57316628087, 5078.771455284485, 5085.0, 5086.237064440326, 5087.414947281036, 4686.578207961265, 4734.0, 4736.052789442126, 4744.169770290186, 4745.762711864393, 4982.327385802942, 5044.2685810558, 5143.27994080829, 5191.506806853343, 5215.400228973641, 5015.974920125449, 5017.949820501822, 5021.799128034911, 5037.874053148701, 5077.720725360066, 5105.540501354896, 5139.049275883974, 5156.278121063054, 5157.63896527247, 5159.432462429129, 4810.831620893252, 4882.170031094723, 4937.136001199776, 4943.0, 5046.268291097844, 4961.8511444656715, 4968.72672003036, 4980.352554168007, 4988.251762235621, 4992.301077849104, 4234.322508398703, 4235.597618226264, 4251.44731184944, 4300.290452075373, 4305.203537345601, 3696.9815150924073, 3698.6301369863336, 3724.404095344732, 3728.6084961079237, 3787.772733635991, 4313.072689371737, 4317.330813723878, 4340.218760623118, 4398.538153493837, 4400.581944715247, 5077.26379674945, 5084.822031228963, 5104.0, 5106.540411362995, 5141.717205553656, 4302.934591396147, 4309.655227581786, 4359.17175736609, 4392.956070439254, 4397.450318710161, 4423.933640995388, 4514.052049069732, 4546.340780586796, 4560.498345182027, 4563.06457176278, 4758.690685105457, 4765.213739733044, 4766.213574760126, 4844.588210002189, 4855.223164293626, 5039.193729003342, 5076.923846142237, 5104.84685459422, 5106.234064890296, 5111.821086261963, 4644.047970166175, 4715.929261061018, 4758.428988521399, 4788.090262850047, 4855.101806165871, 4990.675606085593, 4999.975000125121, 5006.474320196473, 5035.0, 5049.924251136163, 5054.0, 5070.923936140887, 5071.492850714899, 5073.7716802743225, 5111.769970351269, 4737.28940658904, 4886.364772579542, 4894.0, 4918.508149185053, 4957.35554377943, 4903.31353610502, 4953.72754498506, 4960.107180707508, 4977.925331119964, 4992.226204938241, 4881.0, 4925.605951523941, 4928.334674818853, 4933.407991040955, 4945.901081978413, 4721.622270218443, 4933.062718083553, 4951.5048495150195, 4963.602911767122, 5018.924716129188, 4912.0, 4913.582345500529, 4914.189158788759, 4925.236588328815, 4941.209406494943], "value": 4633.6150994930895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.5073784331944, 562.5527705474148, 572.8625129968809, 581.8429024163476, 584.0363400389359, 28386.432271354588, 38787.418188727155, 40009.0, 40015.399769003416, 44163.77918110413, 38721.0, 39160.21679566407, 39166.21667566647, 39171.0, 39187.80406097965, 7658.88511672324, 7786.3381612563, 7797.844043119134, 7800.0, 7804.882926756103, 7433.442491813109, 9836.3606365586, 10058.49707514625, 10082.89917100827, 10377.792444151113, 9969.950150249275, 10057.346272492301, 10073.546690398913, 10095.949520252425, 10128.189744820433, 9871.802563948717, 9934.254930880214, 9945.701628951143, 9962.70111896637, 9963.352382095141, 9928.80142397148, 9987.151092157175, 9992.550335234859, 9998.95000525, 9999.200063994864, 37945.62054379449, 38234.617653823385, 38289.425658615146, 38301.61698383036, 38307.61692383068, 3023.8034527755626, 3079.59965204522, 3195.9680403195903, 3301.900942971715, 3340.365330587205, 29050.27374315638, 29579.408411831642, 29837.0, 29881.850590747126, 29899.0, 3175.666555011737, 3287.4904389819617, 3378.3074469733647, 3524.929501409983, 3525.5593050868642, 270511.0, 272023.0, 273262.0, 273364.0, 273819.0, 31379.68620313813, 32500.349993000007, 53728.0, 53951.14439700446, 53964.460355395946, 33169.83415082909, 33227.83386083102, 33265.66734332674, 33308.333833323195, 33445.665543344745, 12380.690482737824, 12456.0, 12471.0, 12471.064670149775, 12505.624831255076, 9468.763280918032, 10321.122704570196, 10606.787864242826, 10692.09117225029, 10713.89286107129, 7642.617869106522, 7718.884216736754, 7792.688292468238, 7807.219278072147, 7807.765767026998, 3041.695830416985, 3115.7663175261946, 3117.0960421477516, 3132.7023932726356, 3149.984250078735, 828.6892415344246, 862.2713806833216, 901.2789768185426, 937.1378331934636, 938.1275413865035, 25752.484950300888, 26038.86980565085, 27026.864865675547, 27143.0, 27483.587746183824, 954.4368822394821, 961.8797650293714, 982.8673129127619, 1004.1715584642699, 1028.1671845804922, 14048.350341821024, 14065.43738250479, 14235.145891246559, 14340.354684039243, 14589.270536473134, 370297.0, 371608.0, 372007.0, 374286.0, 378025.0, 47929.76035119802, 48192.759036204596, 48258.5174148254, 48277.75861120742, 48343.27485087656, 1031.8710161229847, 1093.2128867215592, 1131.1685910855679, 1190.6725650446197, 1190.7023244188954, 24930.878110485435, 28970.13089607315, 29469.705302946695, 29513.409731805674, 29530.704692952797, 41517.79241103775, 41759.582404176785, 41918.99999999878, 41925.742227733215, 42002.579974201086, 293276.0, 293527.0, 293690.0, 294156.0, 294312.0, 23724.169654062025, 24389.26832195037, 24424.511509770062, 24578.50842983166, 24599.262022139363, 39447.605523944396, 39945.60054399419, 40533.79733101316, 40993.385099224135, 41983.0, 33136.33727325488, 35856.0, 36412.0, 36419.817900910326, 37158.0, 35036.64963350334, 35139.29721405609, 35255.29489410249, 35334.29331413409, 35682.46476302806, 33728.3254334907, 34187.0, 34869.47695784616, 34963.82518087495, 35046.82476587601, 29678.258043549085, 32560.34879302353, 32601.510977335834, 32635.510467342534, 32688.83655581707, 24210.0, 24794.876025619757, 25093.372665683502, 25206.052160063562, 25320.873395632905], "value": 16584.163792130505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.16645719871525, 344.6364085889386, 353.73646633258227, 354.9272399158172, 372.30936612583656, 12767.936160319197, 16129.596760080989, 16213.756793648105, 16866.24101915414, 17897.105144742774, 15120.546383608482, 15152.696946061073, 15293.464728734496, 15361.846381536181, 15467.0, 4840.419149702041, 5055.191169412895, 5064.442911279756, 5122.769475373599, 5303.7082960437165, 3426.7943923364555, 5316.441773613773, 5343.332083489564, 5357.7589008494715, 5358.303420555322, 5130.563902068328, 5381.273528073699, 5417.241586177938, 5458.590605704569, 5479.561635069204, 5236.607254455931, 5385.353757549119, 5428.83713488596, 5434.2935418395755, 5435.347758269013, 5183.481651834824, 5255.816046438356, 5258.579313654898, 5262.815801446931, 5267.104592219293, 12337.074719396081, 12365.0, 12440.626781196579, 12568.874311256863, 12669.809952850715, 1852.3331600623765, 1882.0213488985787, 1893.479293194369, 1954.8142926421967, 1999.0804230054193, 8664.22022018015, 8872.822543549157, 8933.776655583597, 9179.908200917973, 9283.210927071206, 1717.0813614716067, 1723.9482815515555, 1727.3263427263378, 1730.8528775054135, 1787.2225581871928, 65738.3426165737, 69498.0, 69946.30053699449, 70033.0, 71023.0, 10031.69904902854, 10201.0, 10223.335483193718, 10307.793844123076, 10450.68647940563, 14067.929660351632, 14068.507602233873, 14068.648283792987, 14072.859271407155, 14077.648058798408, 7115.679794409264, 7366.447516436181, 7531.133919599207, 7616.8476630467085, 7630.923690763021, 7083.150021997393, 7099.503034787618, 7258.49190556656, 7282.3809976152625, 7313.670884810196, 4336.848210312624, 4437.800298986554, 4442.222611043055, 4516.593506584423, 4523.258545460021, 1735.9132043397778, 1742.7037403641157, 1779.3238569343566, 1811.447508509917, 1854.2953677602425, 863.3611127765408, 868.7176667583061, 910.9453432794054, 915.4187091197138, 915.6017132547363, 16575.336986520404, 16705.248263828158, 17310.134493275287, 17414.0, 17686.469405917844, 870.0337237842522, 870.8214815962713, 876.369014309696, 879.0374539878823, 879.9660399031113, 9346.0, 9726.805463890823, 9864.901350986542, 9878.407295562289, 9973.301868869255, 393976.0, 400835.0, 419793.0, 421630.0, 421937.0, 46108.769456153175, 78015.60992195003, 79327.60336198282, 80320.0, 80711.0, 865.2502770149902, 877.7630039889194, 890.5235698901057, 894.3471265975874, 902.359324879343, 15105.54683359501, 15246.923765381101, 15604.687906241585, 15933.840661593236, 15996.680066398374, 32210.0, 36848.63151368559, 37536.812315938245, 38012.0, 39205.60794392019, 52019.73990130025, 52486.0, 52800.24797768219, 53452.46547534475, 53979.46020539902, 7601.3918886487845, 7918.733002719538, 7937.32532734724, 8029.759107226792, 8156.143604921398, 11679.76640467203, 12001.099917506048, 12047.819282710941, 12172.695682608006, 12307.815382769086, 10230.23273254494, 10293.794124117625, 10485.213608979204, 10589.629362972259, 10975.890241097486, 10502.4223667699, 10579.735506612396, 10581.894181058398, 10601.575936962436, 10629.149668026384, 10594.523246454082, 10636.30863993838, 10707.0, 10731.087857532197, 10865.293755905841, 6496.155499784998, 6862.382385585322, 7023.719051237893, 7181.425485961006, 8162.673493060449, 8860.512671803113, 10282.383057016603, 10383.740406489898, 10485.9475702621, 10827.350358978487], "value": 8076.293366282138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.949442022319, 1369.137443410651, 1429.556837380412, 1495.970080598388, 1699.660067986403, 6969.965150174248, 7709.113451953027, 7974.920250797498, 8089.029316482016, 8274.172582741723, 8077.474964127327, 8127.471714338564, 8147.918520814805, 8151.551664658441, 8158.347332213423, 2176.6843807647883, 2200.3508964855405, 2201.636729939566, 2201.9119235230573, 2212.358416059349, 2131.7548481924546, 2188.059134572133, 2221.1226565506613, 2223.8220942324656, 2226.7661895500983, 2166.945826354338, 2178.945526361838, 2179.171914672422, 2188.627933251343, 2192.8903554822273, 2122.1829595605727, 2124.2671278408925, 2127.744670639533, 2130.009545561306, 2134.4877229464973, 2054.558269971963, 2116.6930795034787, 2120.014193400061, 2120.151939224307, 2125.7130287411155, 8030.478018928809, 8173.223543763333, 8190.2628763410985, 8200.097989221182, 8202.630881610341, 4456.532064133252, 4642.0, 4647.1402790483535, 4674.976625116853, 4675.649326300507, 2844.9431011379656, 2987.3129180288656, 3041.391721655678, 3053.5724998500227, 3060.5103183490533, 921.2353746390547, 938.7559234598986, 940.3229674634321, 952.6808519146094, 975.878015248094, 10962.0, 11061.446927653586, 11070.778584428428, 11079.446027698583, 11099.55601775928, 3955.564887862333, 3986.1828325193264, 4113.691473139466, 4136.524299705572, 4138.213739389506, 8862.42394244372, 8877.20105190536, 8883.067277935854, 8884.955575222211, 8890.688825891191, 4541.227991241497, 4548.840790572314, 4551.38556294896, 4553.180427523078, 4556.2937744649635, 3547.7693949893173, 4058.6550143237564, 4187.413762073312, 4243.702940794176, 4247.086876321606, 3385.966140338565, 3394.2023624448234, 3496.7727097738566, 3511.1046683095647, 3513.5959364672876, 956.6221342569785, 970.8398114311201, 974.4737841565475, 980.632262901412, 982.9115379615869, 779.8089468080357, 810.0117856215408, 814.0434988888032, 814.3932770086324, 816.7345612675786, 5116.744162791845, 5157.071727089085, 5267.631265811357, 5273.0, 5394.78420863161, 373.32800958275, 385.5970510816204, 389.5889836222812, 390.7811625489678, 396.2411981056245, 3584.551931008624, 3592.6586974237407, 3593.2274560968995, 3639.3085313790293, 3680.871169509054, 9800.0, 10194.388336699822, 10253.794924101476, 10270.229732770073, 10270.6405275815, 10211.897881021243, 10213.182945364353, 10217.0, 10217.846732299022, 10228.744281392876, 7558.093028836574, 7566.886496702554, 7607.35337496319, 7675.616219189018, 7692.999910011663, 5270.183121616155, 5278.155495120763, 5373.811916582901, 5495.450454954473, 5498.890022199454, 9046.095390460901, 9612.327137100336, 9614.230861530921, 9616.663416780659, 9623.5669394876, 9063.13900179482, 9485.098915603006, 9593.568289426852, 9656.034396560288, 9683.757906052406, 6110.633361998294, 6180.412860778219, 6932.133483314586, 7070.893936591058, 7082.752103676346, 7453.627318633939, 7679.232076792276, 7690.961545192238, 7706.576138312446, 7706.7687969361, 6623.735050597922, 6780.69486873082, 6806.489513286622, 6943.0, 7041.823954400976, 6773.085633439619, 6847.452203823583, 6864.965675171592, 6912.585244885323, 6940.0, 6230.283517395557, 6325.335839736761, 6327.430531252209, 6333.0, 6340.841478963062, 4537.705049171794, 4554.36238926544, 4604.21728306182, 4710.81156753726, 4792.0895029944195, 4596.563326483979, 4598.149342371672, 4615.192341340185, 4692.41344831896, 4854.635902307412], "value": 4067.936088422285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.9515887140315, 274.83509894063565, 281.99999999999994, 284.78822609795316, 284.8860455817673, 4880.145974454474, 4888.460135057455, 4898.044881248149, 4907.779149938248, 4912.754362281889, 4519.254323036703, 4678.5789278964885, 4709.529047095289, 4711.128441238365, 4816.397950256218, 3602.7297952653525, 3648.781073135607, 3675.779453232801, 3710.257948410329, 3738.5139931808963, 3724.720645951551, 3742.2328422673286, 3748.0, 3792.9810350948346, 3799.183175617242, 3636.909077273076, 3721.255748850227, 3744.4008958566633, 3746.3443897317998, 3758.2295629396044, 3721.3673675475097, 3745.793981331026, 3748.6063963283736, 3749.2378582066385, 3763.360228761117, 3279.3441311737747, 3280.2947366316357, 3280.6883346082086, 3290.3090351026312, 3294.884679036222, 4760.33355330254, 4761.833335833229, 4768.928466073012, 4772.830656489147, 4783.3064205690325, 4155.542890282067, 4190.476190476191, 4240.406343111967, 4249.405083288342, 4286.357046443059, 4954.133026720309, 4973.278874563168, 4982.77577509013, 4986.4265609455015, 5019.397672279313, 3533.452314891196, 3551.5738111426795, 3557.7763897564023, 3564.411872041136, 3594.19130695594, 4984.700917944899, 5261.158214685632, 5328.680279183222, 5341.545968592674, 5348.732563371816, 3534.876279330211, 3544.627814079536, 3557.6620221079475, 3573.4282514797505, 3577.821108944542, 3602.9819850900576, 3622.5471816022996, 3630.419132938717, 3631.3100510902846, 3637.5998640149564, 4184.14225083857, 4198.9370159447635, 4206.6003729646245, 4212.473440819897, 4216.599423054805, 4878.731669758126, 4971.6271279654175, 5035.546800787946, 5044.218146187347, 5083.847484575469, 1235.7281398092405, 1249.2816630437535, 1251.9546178940504, 1252.630474010169, 1252.7431876465303, 779.3453499060817, 789.2344425906862, 823.1192623892377, 832.4422636833336, 848.9278411335106, 4405.867823965286, 4413.536578659258, 4442.666799990014, 4464.0, 4491.416115904912, 5177.7152256625495, 5183.326167598264, 5190.558802501754, 5216.791328346823, 5229.529342359206, 4250.872473825791, 4449.599536041707, 4483.349914262414, 4492.101579684077, 4499.100179963955, 2738.945221095607, 2747.052266967893, 2748.9725102748716, 2753.518134326485, 2753.655793025872, 4822.300766388854, 4830.0, 4868.294097355934, 4871.391076115486, 4879.048585525859, 5071.8985620287385, 5173.689578625294, 5242.633015688865, 5244.187150991603, 5249.448807875195, 4817.542333478314, 4841.3222148899185, 4854.344663470528, 4855.150348689036, 4876.146674331978, 5114.181730923035, 5128.743352924701, 5139.408967968731, 5148.279240906201, 5175.774417000725, 4781.258904869751, 5010.974945125251, 5025.3718285214345, 5032.949670503248, 5059.873503162303, 4842.588379987599, 4869.269609558594, 4875.463698993037, 4889.7799599018845, 4894.755262236945, 3536.6463353664426, 3546.3971124909344, 3553.840077196584, 3558.6619271169197, 3569.464580312974, 4921.8523444296725, 5144.948550514597, 5152.381714194321, 5154.381474223117, 5197.324347834758, 4977.352944117241, 5068.417132029864, 5069.847904562869, 5096.617753668415, 5098.0, 5035.496450355082, 5047.899042019066, 5054.772535235849, 5056.924146137737, 5064.82273120439, 4815.807367705253, 4963.751812409438, 5023.2716256143385, 5061.620378471555, 5067.13858644024, 4837.830675926326, 4960.454350021567, 5118.334616499831, 5125.205593133071, 5134.409542902614, 5022.296878436949, 5033.094043072282, 5061.392632884077, 5065.493450654905, 5081.898362032813], "value": 3765.0929012815413, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.6402568523099, 708.6385943168979, 719.5574721546251, 731.6524650790873, 734.1887214627833, 12970.870291297084, 13596.388162532685, 13636.250006249653, 13768.86231137687, 14289.642758931019, 12718.745625087493, 12789.872101279008, 12794.424250908709, 12798.744025119515, 12799.872001280008, 613.7054213977293, 740.3595889555536, 741.8961345411647, 766.5898744171869, 768.6387397922967, 770.7841804294807, 771.1863983497408, 771.8456308738247, 773.9187385324544, 774.020863607537, 809.2756982500688, 812.155358427238, 812.9227723366271, 813.3330668851544, 813.6175997281268, 650.6811662285494, 785.3913217256614, 807.3702512040618, 812.1391325195311, 812.5977641067709, 587.967661778602, 590.852286928268, 592.0556712044282, 592.1916583863039, 593.1843714892024, 13024.41390137446, 13037.348132593332, 13043.86956130436, 13047.282399468026, 13076.411561479756, 12459.813102803466, 12751.234925904475, 12949.611511654666, 13044.739105217843, 13239.801402978963, 11919.702007449796, 12234.755304893852, 12238.388080595934, 12289.815652765215, 12527.874721252852, 4073.388991651246, 4090.141070375225, 4155.21051000312, 4158.126793373365, 4192.4759405074365, 64785.0, 64848.67575662091, 64887.0, 64922.350776492574, 64959.0, 9796.118349348593, 10040.748981275527, 10325.793484130276, 10352.5341359639, 10658.893411065945, 18980.43058708241, 19153.904230478758, 19290.324838630862, 19331.51671208231, 19334.129964151365, 6553.770618028441, 6556.475481961527, 6583.30875258091, 6623.172103487064, 6863.0, 3724.925501490009, 3781.8865434037025, 3797.582265950743, 3818.59904710007, 3833.3291673956946, 2744.917652470429, 2845.8007939444456, 2846.957295640567, 2852.1871266688895, 2853.9001134960176, 443.4744827379566, 486.7882471125072, 487.24963556123174, 495.6010411618682, 499.2910067703869, 540.9026375252414, 585.4906231578553, 590.2592246730326, 628.6887990444759, 635.190132580963, 8309.127541608044, 8608.48349099056, 8637.222649961534, 8771.429857059271, 8953.0, 491.86965454154677, 521.4681025354193, 575.7696921231541, 612.7181496511565, 616.4236438929615, 4030.455888455021, 4050.9594904051173, 4556.407667003268, 4647.0, 4907.28357851449, 99009.50495247477, 102778.0, 102975.48512257391, 105592.0, 107589.4620526908, 68082.0, 82392.0, 83157.58421207855, 85645.0, 88330.55834720786, 74216.62891685507, 75208.62395688095, 76023.61988190023, 76463.61768191123, 76734.23265767383, 7905.683772649029, 8436.957815210884, 8454.196851299117, 8599.097094804956, 8664.35017373687, 44272.778636107905, 45631.0, 45704.77147614374, 45765.31352029789, 45978.31032534582, 58102.4189758097, 64585.0, 68134.20102377032, 68396.0, 68436.0, 11441.771164576829, 11471.230158730159, 11848.0, 11892.345920974429, 11916.344601047025, 13002.544910928451, 13434.193948363129, 13452.327383630975, 13554.186748795104, 13865.445382185004, 11204.103671706407, 11436.142289328167, 11636.243644163102, 11698.649040528799, 12065.939670301592, 12419.565315213924, 12419.565315213924, 12675.30285834288, 12688.492460301484, 13017.869821301665, 11419.14356423255, 11501.712457188638, 11889.28664280146, 11890.0, 12331.630051098482, 9679.370840895319, 9750.0, 9900.801983960137, 10300.433476158883, 10410.791784164427, 9756.414615123116, 9894.851577226204, 10522.105621021992, 10617.309874858109, 10814.243002989715], "value": 6705.297325068876, "unit": "score"}], "name": "dromaeo_css", "value": 6320.337805631477, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 116.0, 115.0, 114.0, 114.0, 116.0, 116.0, 116.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 124.0, 178.0, 123.0, 132.0, 154.0, 129.0, 179.0, 123.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 163.0, 161.0, 161.0, 163.0, 161.0, 163.0, 166.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 103.0, 77.0, 77.0, 76.0, 75.0, 115.0, 76.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 126.0, 85.0, 88.0, 87.0, 120.0, 85.0, 85.0, 128.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 149.0, 146.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 64.0, 64.0, 66.0, 64.0, 63.0, 63.0, 63.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 81.0, 80.0, 80.0, 81.0, 82.0, 89.0, 81.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 136.0, 133.0, 135.0, 133.0, 133.0, 132.0, 140.0, 156.0, 141.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 181.0, 172.0, 173.0, 177.0, 176.0, 169.0, 171.0, 172.0, 171.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 68.0, 74.0, 72.0, 70.0, 68.0, 68.0, 67.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1553.1000000000004, "alertThreshold": 2.0}]} 08:37:32 INFO - Return code: 0 08:37:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:37:32 INFO - # TBPL SUCCESS # 08:37:32 INFO - Running post-action listener: _resource_record_post_action 08:37:32 INFO - [mozharness: 2016-08-10 15:37:32.739684Z] Finished run-tests step (success) 08:37:32 INFO - Running post-run listener: _resource_record_post_run 08:37:32 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 538087424; Read time: 16; Write time: 598164 08:37:32 INFO - TinderboxPrint: CPU usage
13.0% 08:37:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:37:32 INFO - TinderboxPrint: I/O write bytes / time
538,087,424 / 598,164 08:37:32 INFO - TinderboxPrint: CPU idle
8,343.2 (87.0%) 08:37:32 INFO - TinderboxPrint: CPU user
1,202.2 (12.5%) 08:37:32 INFO - TinderboxPrint: Swap in / out
0 / 0 08:37:32 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 247984128; Read time: 0; Write time: 368024 08:37:32 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 289636352; Read time: 0; Write time: 230092 08:37:33 INFO - Running post-run listener: _upload_blobber_files 08:37:33 INFO - Blob upload gear active. 08:37:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:37:33 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 08:37:33 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:37:33 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:37:33 INFO - (blobuploader) - INFO - Open directory for files ... 08:37:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:37:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:37:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:37 INFO - (blobuploader) - INFO - Done attempting. 08:37:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:37:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:37:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:38 INFO - (blobuploader) - INFO - Done attempting. 08:37:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:37:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:37:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:37:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:39 INFO - (blobuploader) - INFO - Done attempting. 08:37:39 INFO - (blobuploader) - INFO - Iteration through files over. 08:37:39 INFO - Return code: 0 08:37:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:37:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:37:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73940c6fc175e56ba4c971221a087ef42d3b8eb81c0a48ba286d8e1ab2e76c1726cb35bd81c5cd36c3e99bc36bad0a77070e757b6d45858cc652bc4f97ec250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6c8af2b5ab5eea67b3e52623ef9e56c6add92c4ba5e80dcaf9e9c25fb93169edcbee6632001e11983d496080f10a66102369c5882ce95edbf847d7ab44d02db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d9b230476eaa095619ce8c93501dc0e384e6dd1c3ce8fe1b545f7f460f0bfa359389f0b11b0f28bb6d6db91813b983fa06f630cc32dba47f1e0dcc79d144cb9"} 08:37:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:37:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:37:39 INFO - Contents: 08:37:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73940c6fc175e56ba4c971221a087ef42d3b8eb81c0a48ba286d8e1ab2e76c1726cb35bd81c5cd36c3e99bc36bad0a77070e757b6d45858cc652bc4f97ec250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6c8af2b5ab5eea67b3e52623ef9e56c6add92c4ba5e80dcaf9e9c25fb93169edcbee6632001e11983d496080f10a66102369c5882ce95edbf847d7ab44d02db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d9b230476eaa095619ce8c93501dc0e384e6dd1c3ce8fe1b545f7f460f0bfa359389f0b11b0f28bb6d6db91813b983fa06f630cc32dba47f1e0dcc79d144cb9"} 08:37:39 INFO - Running post-run listener: copy_logs_to_upload_dir 08:37:39 INFO - Copying logs to upload dir... 08:37:39 INFO - mkdir: /builds/slave/test/build/upload/logs 08:37:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.534832 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-10 08:37:37.467801) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 08:37:37.471855) ========= 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=52faae308a556c440401f8e5000001e8-1470842218.891614-179384487 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-Non-PGO/sha512/73940c6fc175e56ba4c971221a087ef42d3b8eb81c0a48ba286d8e1ab2e76c1726cb35bd81c5cd36c3e99bc36bad0a77070e757b6d45858cc652bc4f97ec250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6c8af2b5ab5eea67b3e52623ef9e56c6add92c4ba5e80dcaf9e9c25fb93169edcbee6632001e11983d496080f10a66102369c5882ce95edbf847d7ab44d02db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d9b230476eaa095619ce8c93501dc0e384e6dd1c3ce8fe1b545f7f460f0bfa359389f0b11b0f28bb6d6db91813b983fa06f630cc32dba47f1e0dcc79d144cb9"} build_url:https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/bHjp4JttTSSfU9qpSVVHwQ/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-Non-PGO/sha512/73940c6fc175e56ba4c971221a087ef42d3b8eb81c0a48ba286d8e1ab2e76c1726cb35bd81c5cd36c3e99bc36bad0a77070e757b6d45858cc652bc4f97ec250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6c8af2b5ab5eea67b3e52623ef9e56c6add92c4ba5e80dcaf9e9c25fb93169edcbee6632001e11983d496080f10a66102369c5882ce95edbf847d7ab44d02db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d9b230476eaa095619ce8c93501dc0e384e6dd1c3ce8fe1b545f7f460f0bfa359389f0b11b0f28bb6d6db91813b983fa06f630cc32dba47f1e0dcc79d144cb9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 08:37:37.498518) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:37:37.498872) ========= 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=52faae308a556c440401f8e5000001e8-1470842218.891614-179384487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004338 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 08:37:37.581720) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 08:37:37.582067) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 08:37:37.582414) ========= ========= Total master_lag: 0.19 =========