builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1468278744.95 results: success (0) buildid: 20160711133152 builduid: 68c12927bce84086bd7c73f9611ba76f revision: 9189a2cc39d0c35e8823349aa0b4057ab8167b32 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:24.946867) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:24.947254) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:24.947874) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:24.969280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:24.969537) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 16:12:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-07-11 16:12:25 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.069769 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:25.479604) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:25.479926) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016543 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:25.531338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:25.531703) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 9189a2cc39d0c35e8823349aa0b4057ab8167b32 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 9189a2cc39d0c35e8823349aa0b4057ab8167b32 --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=97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 16:12:25,875 truncating revision to first 12 chars 2016-07-11 16:12:25,875 Setting DEBUG logging. 2016-07-11 16:12:25,875 attempt 1/10 2016-07-11 16:12:25,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/9189a2cc39d0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 16:12:26,222 unpacking tar archive at: mozilla-inbound-9189a2cc39d0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.675657 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:26.230589) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:26.231275) ========= script_repo_revision: 9189a2cc39d0c35e8823349aa0b4057ab8167b32 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:26.232068) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:26.232334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 16:12:26.244259) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-07-11 16:12:26.244561) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:12:26 INFO - MultiFileLogger online at 20160711 16:12:26 in /builds/slave/test 16:12:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:12:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:26 INFO - {'append_to_log': False, 16:12:26 INFO - 'base_work_dir': '/builds/slave/test', 16:12:26 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:12:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:26 INFO - 'branch': 'Mozilla-Inbound', 16:12:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:26 INFO - 'config_files': ('talos/linux_config.py',), 16:12:26 INFO - 'default_actions': ('clobber', 16:12:26 INFO - 'read-buildbot-config', 16:12:26 INFO - 'download-and-extract', 16:12:26 INFO - 'populate-webroot', 16:12:26 INFO - 'create-virtualenv', 16:12:26 INFO - 'install', 16:12:26 INFO - 'run-tests'), 16:12:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:26 INFO - 'download_minidump_stackwalk': True, 16:12:26 INFO - 'download_symbols': 'ondemand', 16:12:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:26 INFO - 'installer_path': 'installer.exe', 16:12:26 INFO - 'log_level': 'info', 16:12:26 INFO - 'log_name': 'talos', 16:12:26 INFO - 'log_to_console': True, 16:12:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:12:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:12:26 INFO - 'opt_config_files': (), 16:12:26 INFO - 'pip_index': False, 16:12:26 INFO - 'sps_profile': False, 16:12:26 INFO - 'sps_profile_interval': 0, 16:12:26 INFO - 'suite': 'dromaeojs', 16:12:26 INFO - 'system_bits': '32', 16:12:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:26 INFO - 'title': 'talos-linux64-ix-068', 16:12:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:26 INFO - 'use_talos_json': True, 16:12:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:26 INFO - 'work_dir': 'build'} 16:12:26 INFO - ##### 16:12:26 INFO - ##### Running clobber step. 16:12:26 INFO - ##### 16:12:26 INFO - Running pre-action listener: _resource_record_pre_action 16:12:26 INFO - Running main action method: clobber 16:12:26 INFO - rmtree: /builds/slave/test/build 16:12:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:27 INFO - Running post-action listener: _resource_record_post_action 16:12:27 INFO - ##### 16:12:27 INFO - ##### Finished clobber step (success) 16:12:27 INFO - ##### 16:12:27 INFO - ##### 16:12:27 INFO - ##### Running read-buildbot-config step. 16:12:27 INFO - ##### 16:12:27 INFO - Running pre-action listener: _resource_record_pre_action 16:12:27 INFO - Running main action method: read_buildbot_config 16:12:27 INFO - Using buildbot properties: 16:12:27 INFO - { 16:12:27 INFO - "project": "", 16:12:27 INFO - "product": "firefox", 16:12:27 INFO - "script_repo_revision": "production", 16:12:27 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:12:27 INFO - "repository": "", 16:12:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:12:27 INFO - "buildid": "20160711133152", 16:12:27 INFO - "pgo_build": "True", 16:12:27 INFO - "basedir": "/builds/slave/test", 16:12:27 INFO - "buildnumber": 1232, 16:12:27 INFO - "slavename": "talos-linux64-ix-068", 16:12:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:12:27 INFO - "platform": "ubuntu64_hw", 16:12:27 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:12:27 INFO - "branch": "mozilla-inbound", 16:12:27 INFO - "repo_path": "integration/mozilla-inbound", 16:12:27 INFO - "revision": "9189a2cc39d0c35e8823349aa0b4057ab8167b32", 16:12:27 INFO - "stage_platform": "linux64-pgo", 16:12:27 INFO - "builduid": "68c12927bce84086bd7c73f9611ba76f", 16:12:27 INFO - "slavebuilddir": "test" 16:12:27 INFO - } 16:12:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:12:27 INFO - Running post-action listener: _resource_record_post_action 16:12:27 INFO - ##### 16:12:27 INFO - ##### Finished read-buildbot-config step (success) 16:12:27 INFO - ##### 16:12:27 INFO - ##### 16:12:27 INFO - ##### Running download-and-extract step. 16:12:27 INFO - ##### 16:12:27 INFO - Running pre-action listener: _resource_record_pre_action 16:12:27 INFO - Running main action method: download_and_extract 16:12:27 INFO - mkdir: /builds/slave/test/build/tests 16:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:27 INFO - https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:12:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:12:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:12:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:12:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:12:28 INFO - Downloaded 1361 bytes. 16:12:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:12:28 INFO - Using the following test package requirements: 16:12:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:12:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:12:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'jsshell-linux-x86_64.zip'], 16:12:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:12:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:12:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:12:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:12:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:12:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:12:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:28 INFO - https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:12:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:12:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:12:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:12:29 INFO - Downloaded 24307618 bytes. 16:12:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:12:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:12:30 INFO - Return code: 0 16:12:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:12: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:30 INFO - https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:12:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:12:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:12:32 INFO - Downloaded 24307618 bytes. 16:12:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:12:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:12:33 INFO - Return code: 0 16:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:33 INFO - https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:12:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:12:35 INFO - Downloaded 14403317 bytes. 16:12:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:12:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:12:35 INFO - Return code: 0 16:12:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:35 INFO - https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:12:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:12:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:12:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:12:38 INFO - Downloaded 60489570 bytes. 16:12:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:12:38 INFO - mkdir: /builds/slave/test/properties 16:12:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:38 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:38 INFO - Contents: 16:12:38 INFO - build_url:https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:12:39 INFO - Running post-action listener: _resource_record_post_action 16:12:39 INFO - Running post-action listener: set_extra_try_arguments 16:12:39 INFO - ##### 16:12:39 INFO - ##### Finished download-and-extract step (success) 16:12:39 INFO - ##### 16:12:39 INFO - ##### 16:12:39 INFO - ##### Running populate-webroot step. 16:12:39 INFO - ##### 16:12:39 INFO - Running pre-action listener: _resource_record_pre_action 16:12:39 INFO - Running main action method: populate_webroot 16:12:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:39 INFO - '--tppagecycles', 16:12:39 INFO - '7'], 16:12:39 INFO - 'tests': ['tsvgm']}}, 16:12:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['damp', 'tps']}, 16:12:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'tests': ['damp', 'tps']}, 16:12:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['dromaeo_dom']}, 16:12:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['basic_compositor_video']}, 16:12:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:12:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['a11yr', 16:12:39 INFO - 'ts_paint', 16:12:39 INFO - 'tpaint', 16:12:39 INFO - 'sessionrestore', 16:12:39 INFO - 'sessionrestore_no_auto_restore', 16:12:39 INFO - 'tabpaint']}, 16:12:39 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:39 INFO - 'ts_paint', 16:12:39 INFO - 'tpaint', 16:12:39 INFO - 'sessionrestore', 16:12:39 INFO - 'sessionrestore_no_auto_restore', 16:12:39 INFO - 'tabpaint']}, 16:12:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:39 INFO - 'ts_paint', 16:12:39 INFO - 'tpaint', 16:12:39 INFO - 'sessionrestore', 16:12:39 INFO - 'sessionrestore_no_auto_restore', 16:12:39 INFO - 'tabpaint']}, 16:12:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:39 INFO - 'ts_paint', 16:12:39 INFO - 'tpaint', 16:12:39 INFO - 'sessionrestore', 16:12:39 INFO - 'sessionrestore_no_auto_restore', 16:12:39 INFO - 'tabpaint']}, 16:12:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['a11yr', 16:12:39 INFO - 'ts_paint', 16:12:39 INFO - 'tpaint', 16:12:39 INFO - 'sessionrestore', 16:12:39 INFO - 'sessionrestore_no_auto_restore', 16:12:39 INFO - 'tabpaint']}, 16:12:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['a11yr', 16:12:39 INFO - 'ts_paint', 16:12:39 INFO - 'tpaint', 16:12:39 INFO - 'sessionrestore', 16:12:39 INFO - 'sessionrestore_no_auto_restore', 16:12:39 INFO - 'tabpaint']}, 16:12:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['tsvgx', 16:12:39 INFO - 'tsvgr_opacity', 16:12:39 INFO - 'tart', 16:12:39 INFO - 'tscrollx', 16:12:39 INFO - 'cart']}, 16:12:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:39 INFO - 'tsvgr_opacity', 16:12:39 INFO - 'tart', 16:12:39 INFO - 'tscrollx', 16:12:39 INFO - 'cart']}, 16:12:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'talos_options': ['--disable-e10s'], 16:12:39 INFO - 'tests': ['tp5o']}, 16:12:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'tests': ['tp5o']}, 16:12:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'talos_options': ['--disable-e10s', 16:12:39 INFO - '--xperf_path', 16:12:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:39 INFO - 'tests': ['tp5n']}, 16:12:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:39 INFO - 'talos_options': ['--xperf_path', 16:12:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:39 INFO - 'tests': ['tp5n']}}, 16:12:39 INFO - 'talos.zip': {'path': '', 16:12:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:39 INFO - Running post-action listener: _resource_record_post_action 16:12:39 INFO - ##### 16:12:39 INFO - ##### Finished populate-webroot step (success) 16:12:39 INFO - ##### 16:12:39 INFO - ##### 16:12:39 INFO - ##### Running create-virtualenv step. 16:12:39 INFO - ##### 16:12:39 INFO - Running pre-action listener: _resource_record_pre_action 16:12:39 INFO - Running main action method: create_virtualenv 16:12:39 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:39 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 16:12:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:39 INFO - Using real prefix '/usr' 16:12:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:39 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:41 INFO - Installing pip.................done. 16:12:41 INFO - Return code: 0 16:12:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:41 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:41 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 16:12:41 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 16:12:41 INFO - Using env: {'DISPLAY': ':0', 16:12:41 INFO - 'HOME': '/home/cltbld', 16:12:41 INFO - 'LANG': 'en_US.UTF-8', 16:12:41 INFO - 'LANGUAGE': 'en_US:en', 16:12:41 INFO - 'LOGNAME': 'cltbld', 16:12:41 INFO - 'MAIL': '/var/mail/cltbld', 16:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:41 INFO - 'MOZ_NO_REMOTE': '1', 16:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:41 INFO - 'NO_EM_RESTART': '1', 16:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:41 INFO - 'PWD': '/builds/slave/test', 16:12:41 INFO - 'SHELL': '/bin/bash', 16:12:41 INFO - 'SHLVL': '1', 16:12:41 INFO - 'TERM': 'linux', 16:12:41 INFO - 'TMOUT': '86400', 16:12:41 INFO - 'USER': 'cltbld', 16:12:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:41 INFO - '_': '/tools/buildbot/bin/python'} 16:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:42 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 16:12:42 INFO - Cleaning up... 16:12:42 INFO - Return code: 0 16:12:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:12:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:42 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:42 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 16:12:42 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 16:12:42 INFO - Using env: {'DISPLAY': ':0', 16:12:42 INFO - 'HOME': '/home/cltbld', 16:12:42 INFO - 'LANG': 'en_US.UTF-8', 16:12:42 INFO - 'LANGUAGE': 'en_US:en', 16:12:42 INFO - 'LOGNAME': 'cltbld', 16:12:42 INFO - 'MAIL': '/var/mail/cltbld', 16:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:42 INFO - 'MOZ_NO_REMOTE': '1', 16:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:42 INFO - 'NO_EM_RESTART': '1', 16:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:42 INFO - 'PWD': '/builds/slave/test', 16:12:42 INFO - 'SHELL': '/bin/bash', 16:12:42 INFO - 'SHLVL': '1', 16:12:42 INFO - 'TERM': 'linux', 16:12:42 INFO - 'TMOUT': '86400', 16:12:42 INFO - 'USER': 'cltbld', 16:12:42 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:42 INFO - '_': '/tools/buildbot/bin/python'} 16:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:42 INFO - Downloading/unpacking psutil>=3.1.1 16:12:42 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 16:12:42 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 16:12:42 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 16:12:42 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 16:12:42 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 16:12:42 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 16:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:44 INFO - Installing collected packages: psutil 16:12:44 INFO - Running setup.py install for psutil 16:12:44 INFO - building 'psutil._psutil_linux' extension 16:12:44 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 16:12:44 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 16:12:44 INFO - building 'psutil._psutil_posix' extension 16:12:44 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 16:12:44 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 16:12:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:44 INFO - Successfully installed psutil 16:12:44 INFO - Cleaning up... 16:12:44 INFO - Return code: 0 16:12:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:12:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:44 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:44 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 16:12:44 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 16:12:44 INFO - Using env: {'DISPLAY': ':0', 16:12:44 INFO - 'HOME': '/home/cltbld', 16:12:44 INFO - 'LANG': 'en_US.UTF-8', 16:12:44 INFO - 'LANGUAGE': 'en_US:en', 16:12:44 INFO - 'LOGNAME': 'cltbld', 16:12:44 INFO - 'MAIL': '/var/mail/cltbld', 16:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:44 INFO - 'MOZ_NO_REMOTE': '1', 16:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:44 INFO - 'NO_EM_RESTART': '1', 16:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:44 INFO - 'PWD': '/builds/slave/test', 16:12:44 INFO - 'SHELL': '/bin/bash', 16:12:44 INFO - 'SHLVL': '1', 16:12:44 INFO - 'TERM': 'linux', 16:12:44 INFO - 'TMOUT': '86400', 16:12:44 INFO - 'USER': 'cltbld', 16:12:44 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:44 INFO - '_': '/tools/buildbot/bin/python'} 16:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:12:45 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 16:12:45 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 16:12:45 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 16:12:45 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 16:12:45 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 16:12:45 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 16:12:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:12:47 INFO - Installing collected packages: mozsystemmonitor 16:12:47 INFO - Running setup.py install for mozsystemmonitor 16:12:47 INFO - Successfully installed mozsystemmonitor 16:12:47 INFO - Cleaning up... 16:12:47 INFO - Return code: 0 16:12:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:47 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:47 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 16:12:47 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 16:12:47 INFO - Using env: {'DISPLAY': ':0', 16:12:47 INFO - 'HOME': '/home/cltbld', 16:12:47 INFO - 'LANG': 'en_US.UTF-8', 16:12:47 INFO - 'LANGUAGE': 'en_US:en', 16:12:47 INFO - 'LOGNAME': 'cltbld', 16:12:47 INFO - 'MAIL': '/var/mail/cltbld', 16:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:47 INFO - 'MOZ_NO_REMOTE': '1', 16:12:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:47 INFO - 'NO_EM_RESTART': '1', 16:12:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:47 INFO - 'PWD': '/builds/slave/test', 16:12:47 INFO - 'SHELL': '/bin/bash', 16:12:47 INFO - 'SHLVL': '1', 16:12:47 INFO - 'TERM': 'linux', 16:12:47 INFO - 'TMOUT': '86400', 16:12:47 INFO - 'USER': 'cltbld', 16:12:47 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:47 INFO - '_': '/tools/buildbot/bin/python'} 16:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:47 INFO - Downloading/unpacking jsonschema==2.5.1 16:12:47 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 16:12:47 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 16:12:47 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 16:12:47 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 16:12:47 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 16:12:47 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 16:12:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:12:49 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 16:12:49 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 16:12:49 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 16:12:49 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 16:12:49 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 16:12:49 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 16:12:49 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:49 INFO - warning: no files found matching '*.txt' 16:12:49 INFO - no previously-included directories found matching 'build' 16:12:49 INFO - no previously-included directories found matching 'dist' 16:12:49 INFO - no previously-included directories found matching '.git*' 16:12:49 INFO - Installing collected packages: jsonschema, functools32 16:12:49 INFO - Running setup.py install for functools32 16:12:49 INFO - warning: no files found matching '*.txt' 16:12:49 INFO - no previously-included directories found matching 'build' 16:12:49 INFO - no previously-included directories found matching 'dist' 16:12:49 INFO - no previously-included directories found matching '.git*' 16:12:49 INFO - Successfully installed jsonschema functools32 16:12:49 INFO - Cleaning up... 16:12:49 INFO - Return code: 0 16:12:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:49 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:49 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 16:12:49 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 16:12:49 INFO - Using env: {'DISPLAY': ':0', 16:12:49 INFO - 'HOME': '/home/cltbld', 16:12:49 INFO - 'LANG': 'en_US.UTF-8', 16:12:49 INFO - 'LANGUAGE': 'en_US:en', 16:12:49 INFO - 'LOGNAME': 'cltbld', 16:12:49 INFO - 'MAIL': '/var/mail/cltbld', 16:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:49 INFO - 'MOZ_NO_REMOTE': '1', 16:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:49 INFO - 'NO_EM_RESTART': '1', 16:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:49 INFO - 'PWD': '/builds/slave/test', 16:12:49 INFO - 'SHELL': '/bin/bash', 16:12:49 INFO - 'SHLVL': '1', 16:12:49 INFO - 'TERM': 'linux', 16:12:49 INFO - 'TMOUT': '86400', 16:12:49 INFO - 'USER': 'cltbld', 16:12:49 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:49 INFO - '_': '/tools/buildbot/bin/python'} 16:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:50 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:50 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 16:12:50 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 16:12:50 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 16:12:50 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 16:12:50 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 16:12:50 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 16:12:52 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 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 16:12:52 INFO - Downloading docopt-0.6.1.tar.gz 16:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:52 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:52 INFO - Running setup.py install for blobuploader 16:12:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:53 INFO - Running setup.py install for requests 16:12:53 INFO - Running setup.py install for docopt 16:12:53 INFO - Successfully installed blobuploader requests docopt 16:12:53 INFO - Cleaning up... 16:12:53 INFO - Return code: 0 16:12:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:53 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:53 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 16:12:53 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 16:12:53 INFO - Using env: {'DISPLAY': ':0', 16:12:53 INFO - 'HOME': '/home/cltbld', 16:12:53 INFO - 'LANG': 'en_US.UTF-8', 16:12:53 INFO - 'LANGUAGE': 'en_US:en', 16:12:53 INFO - 'LOGNAME': 'cltbld', 16:12:53 INFO - 'MAIL': '/var/mail/cltbld', 16:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:53 INFO - 'MOZ_NO_REMOTE': '1', 16:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:53 INFO - 'NO_EM_RESTART': '1', 16:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:53 INFO - 'PWD': '/builds/slave/test', 16:12:53 INFO - 'SHELL': '/bin/bash', 16:12:53 INFO - 'SHLVL': '1', 16:12:53 INFO - 'TERM': 'linux', 16:12:53 INFO - 'TMOUT': '86400', 16:12:53 INFO - 'USER': 'cltbld', 16:12:53 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:53 INFO - '_': '/tools/buildbot/bin/python'} 16:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:53 INFO - Running setup.py (path:/tmp/pip-X8UE7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:53 INFO - Running setup.py (path:/tmp/pip-tTHIkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:53 INFO - Running setup.py (path:/tmp/pip-pfKY3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:54 INFO - Running setup.py (path:/tmp/pip-cOfGxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:54 INFO - Running setup.py (path:/tmp/pip-S1_SqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:54 INFO - Running setup.py (path:/tmp/pip-lGYcz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:54 INFO - Running setup.py (path:/tmp/pip-rVzhph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:54 INFO - Running setup.py (path:/tmp/pip-cUWq0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:54 INFO - Running setup.py (path:/tmp/pip-wvw6xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:54 INFO - Running setup.py (path:/tmp/pip-6xtNCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:54 INFO - Running setup.py (path:/tmp/pip-9EPcWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:54 INFO - Running setup.py (path:/tmp/pip-cVIONL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:54 INFO - Running setup.py (path:/tmp/pip-BICVrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:54 INFO - Running setup.py (path:/tmp/pip-Dlq0Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:54 INFO - Running setup.py (path:/tmp/pip-0xwRtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:54 INFO - Running setup.py (path:/tmp/pip-yPSBs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:54 INFO - Running setup.py (path:/tmp/pip-k49js9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:55 INFO - Running setup.py install for manifestparser 16:12:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Running setup.py install for mozcrash 16:12:55 INFO - Running setup.py install for mozdebug 16:12:55 INFO - Running setup.py install for mozdevice 16:12:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Running setup.py install for mozfile 16:12:55 INFO - Running setup.py install for mozhttpd 16:12:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Running setup.py install for mozinfo 16:12:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Running setup.py install for mozInstall 16:12:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:12:55 INFO - Running setup.py install for mozleak 16:12:55 INFO - Running setup.py install for mozlog 16:12:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Running setup.py install for moznetwork 16:12:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Running setup.py install for mozprocess 16:12:56 INFO - Running setup.py install for mozprofile 16:12:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Running setup.py install for mozrunner 16:12:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Running setup.py install for mozscreenshot 16:12:56 INFO - Running setup.py install for moztest 16:12:56 INFO - Running setup.py install for mozversion 16:12:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:12:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:56 INFO - Cleaning up... 16:12:56 INFO - Return code: 0 16:12:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:56 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:12:56 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 16:12:56 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 16:12:56 INFO - Using env: {'DISPLAY': ':0', 16:12:56 INFO - 'HOME': '/home/cltbld', 16:12:56 INFO - 'LANG': 'en_US.UTF-8', 16:12:56 INFO - 'LANGUAGE': 'en_US:en', 16:12:56 INFO - 'LOGNAME': 'cltbld', 16:12:56 INFO - 'MAIL': '/var/mail/cltbld', 16:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:56 INFO - 'MOZ_NO_REMOTE': '1', 16:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:56 INFO - 'NO_EM_RESTART': '1', 16:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:56 INFO - 'PWD': '/builds/slave/test', 16:12:56 INFO - 'SHELL': '/bin/bash', 16:12:56 INFO - 'SHLVL': '1', 16:12:56 INFO - 'TERM': 'linux', 16:12:56 INFO - 'TMOUT': '86400', 16:12:56 INFO - 'USER': 'cltbld', 16:12:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:56 INFO - '_': '/tools/buildbot/bin/python'} 16:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:57 INFO - Running setup.py (path:/tmp/pip-nKroml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:57 INFO - Running setup.py (path:/tmp/pip-9nyJzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:57 INFO - Running setup.py (path:/tmp/pip-htjzHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:57 INFO - Running setup.py (path:/tmp/pip-2az9bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:57 INFO - Running setup.py (path:/tmp/pip-iwYgTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:57 INFO - Running setup.py (path:/tmp/pip-gR8vi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:57 INFO - Running setup.py (path:/tmp/pip-ptgh3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:57 INFO - Running setup.py (path:/tmp/pip-EMg_c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:57 INFO - Running setup.py (path:/tmp/pip-pIjVVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:57 INFO - Running setup.py (path:/tmp/pip-U055Wl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:57 INFO - Running setup.py (path:/tmp/pip-tvaB0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:57 INFO - Running setup.py (path:/tmp/pip-88L7zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:57 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)) 16:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:57 INFO - Running setup.py (path:/tmp/pip-Qzg4T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:58 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)) 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:58 INFO - Running setup.py (path:/tmp/pip-6O9Lxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:58 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)) 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:58 INFO - Running setup.py (path:/tmp/pip-H0wBWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:58 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)) 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:58 INFO - Running setup.py (path:/tmp/pip-HYlK_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:58 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)) 16:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:58 INFO - Running setup.py (path:/tmp/pip-GYIc1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:58 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)) 16:12:58 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)) 16:12:58 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)) 16:12:58 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)) 16:12:58 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)) 16:12:58 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)) 16:12:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:12:58 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 16:12:58 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 16:12:58 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 16:12:58 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 16:12:58 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 16:12:58 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 16:13:00 INFO - Downloading blessings-1.6.tar.gz 16:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:13:00 INFO - Installing collected packages: blessings 16:13:00 INFO - Running setup.py install for blessings 16:13:00 INFO - Successfully installed blessings 16:13:00 INFO - Cleaning up... 16:13:00 INFO - Return code: 0 16:13:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:13:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:00 INFO - Reading from file tmpfile_stdout 16:13:00 INFO - Current package versions: 16:13:00 INFO - argparse == 1.2.1 16:13:00 INFO - blessings == 1.6 16:13:00 INFO - blobuploader == 1.2.4 16:13:00 INFO - docopt == 0.6.1 16:13:00 INFO - functools32 == 3.2.3-2 16:13:00 INFO - jsonschema == 2.5.1 16:13:00 INFO - manifestparser == 1.1 16:13:00 INFO - mozInstall == 1.12 16:13:00 INFO - mozcrash == 0.17 16:13:00 INFO - mozdebug == 0.1 16:13:00 INFO - mozdevice == 0.48 16:13:00 INFO - mozfile == 1.2 16:13:00 INFO - mozhttpd == 0.7 16:13:00 INFO - mozinfo == 0.9 16:13:00 INFO - mozleak == 0.1 16:13:00 INFO - mozlog == 3.2 16:13:00 INFO - moznetwork == 0.27 16:13:00 INFO - mozprocess == 0.23 16:13:00 INFO - mozprofile == 0.28 16:13:00 INFO - mozrunner == 6.12 16:13:00 INFO - mozscreenshot == 0.1 16:13:00 INFO - mozsystemmonitor == 0.3 16:13:00 INFO - moztest == 0.7 16:13:00 INFO - mozversion == 1.4 16:13:00 INFO - psutil == 3.1.1 16:13:00 INFO - requests == 1.2.3 16:13:00 INFO - wsgiref == 0.1.2 16:13:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:00 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:13:00 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 16:13:00 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 16:13:00 INFO - Using env: {'DISPLAY': ':0', 16:13:00 INFO - 'HOME': '/home/cltbld', 16:13:00 INFO - 'LANG': 'en_US.UTF-8', 16:13:00 INFO - 'LANGUAGE': 'en_US:en', 16:13:00 INFO - 'LOGNAME': 'cltbld', 16:13:00 INFO - 'MAIL': '/var/mail/cltbld', 16:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:00 INFO - 'MOZ_NO_REMOTE': '1', 16:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:00 INFO - 'NO_EM_RESTART': '1', 16:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:00 INFO - 'PWD': '/builds/slave/test', 16:13:00 INFO - 'SHELL': '/bin/bash', 16:13:00 INFO - 'SHLVL': '1', 16:13:00 INFO - 'TERM': 'linux', 16:13:00 INFO - 'TMOUT': '86400', 16:13:00 INFO - 'USER': 'cltbld', 16:13:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:00 INFO - '_': '/tools/buildbot/bin/python'} 16:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 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)) 16:13:00 INFO - Cleaning up... 16:13:00 INFO - Return code: 0 16:13:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:00 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', '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 16:13:00 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 16:13:00 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 16:13:00 INFO - Using env: {'DISPLAY': ':0', 16:13:00 INFO - 'HOME': '/home/cltbld', 16:13:00 INFO - 'LANG': 'en_US.UTF-8', 16:13:00 INFO - 'LANGUAGE': 'en_US:en', 16:13:00 INFO - 'LOGNAME': 'cltbld', 16:13:00 INFO - 'MAIL': '/var/mail/cltbld', 16:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:00 INFO - 'MOZ_NO_REMOTE': '1', 16:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:00 INFO - 'NO_EM_RESTART': '1', 16:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:00 INFO - 'PWD': '/builds/slave/test', 16:13:00 INFO - 'SHELL': '/bin/bash', 16:13:00 INFO - 'SHLVL': '1', 16:13:00 INFO - 'TERM': 'linux', 16:13:00 INFO - 'TMOUT': '86400', 16:13:00 INFO - 'USER': 'cltbld', 16:13:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:00 INFO - '_': '/tools/buildbot/bin/python'} 16:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:13:01 INFO - Cleaning up... 16:13:01 INFO - Return code: 0 16:13:01 INFO - Running post-action listener: _resource_record_post_action 16:13:01 INFO - Running post-action listener: _start_resource_monitoring 16:13:01 INFO - Starting resource monitoring. 16:13:01 INFO - ##### 16:13:01 INFO - ##### Finished create-virtualenv step (success) 16:13:01 INFO - ##### 16:13:01 INFO - ##### 16:13:01 INFO - ##### Running install step. 16:13:01 INFO - ##### 16:13:01 INFO - Running pre-action listener: _resource_record_pre_action 16:13:01 INFO - Running main action method: install 16:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:01 INFO - Reading from file tmpfile_stdout 16:13:01 INFO - Detecting whether we're running mozinstall >=1.0... 16:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:13:01 INFO - Reading from file tmpfile_stdout 16:13:01 INFO - Output received: 16:13:01 INFO - Usage: mozinstall [options] installer 16:13:01 INFO - Options: 16:13:01 INFO - -h, --help show this help message and exit 16:13:01 INFO - -d DEST, --destination=DEST 16:13:01 INFO - Directory to install application into. [default: 16:13:01 INFO - "/builds/slave/test"] 16:13:01 INFO - --app=APP Application being installed. [default: firefox] 16:13:01 INFO - mkdir: /builds/slave/test/build/application 16:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:13:18 INFO - Reading from file tmpfile_stdout 16:13:18 INFO - Output received: 16:13:18 INFO - /builds/slave/test/build/application/firefox/firefox 16:13:18 INFO - Running post-action listener: _resource_record_post_action 16:13:18 INFO - ##### 16:13:18 INFO - ##### Finished install step (success) 16:13:18 INFO - ##### 16:13:18 INFO - ##### 16:13:18 INFO - ##### Running run-tests step. 16:13:18 INFO - ##### 16:13:18 INFO - Running pre-action listener: _resource_record_pre_action 16:13:18 INFO - Running main action method: run_tests 16:13:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:13:18 INFO - Python 2.7.3 16:13:18 INFO - Return code: 0 16:13:18 INFO - grabbing minidump binary from tooltool 16:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:18 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 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:13:18 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 16:13:18 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 16:13:18 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 16:13:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:13:18 INFO - Return code: 0 16:13:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:13:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:13:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:13:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:13:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:13:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 16:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 16:13:18 INFO - Using env: {'DISPLAY': ':0', 16:13:18 INFO - 'HOME': '/home/cltbld', 16:13:18 INFO - 'LANG': 'en_US.UTF-8', 16:13:18 INFO - 'LANGUAGE': 'en_US:en', 16:13:18 INFO - 'LOGNAME': 'cltbld', 16:13:18 INFO - 'MAIL': '/var/mail/cltbld', 16:13:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:13:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:18 INFO - 'MOZ_NO_REMOTE': '1', 16:13:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:18 INFO - 'NO_EM_RESTART': '1', 16:13:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:18 INFO - 'PWD': '/builds/slave/test', 16:13:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:13:18 INFO - 'SHELL': '/bin/bash', 16:13:18 INFO - 'SHLVL': '1', 16:13:18 INFO - 'TERM': 'linux', 16:13:18 INFO - 'TMOUT': '86400', 16:13:18 INFO - 'USER': 'cltbld', 16:13:18 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138', 16:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:18 INFO - '_': '/tools/buildbot/bin/python'} 16:13:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 16:13:18 INFO - mozversion application_buildid: 20160711133152 16:13:18 INFO - mozversion application_changeset: 9189a2cc39d0c35e8823349aa0b4057ab8167b32 16:13:18 INFO - mozversion application_display_name: Nightly 16:13:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:13:18 INFO - mozversion application_name: Firefox 16:13:18 INFO - mozversion application_remotingname: firefox 16:13:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:13:18 INFO - mozversion application_vendor: Mozilla 16:13:18 INFO - mozversion application_version: 50.0a1 16:13:18 INFO - mozversion platform_buildid: 20160711133152 16:13:18 INFO - mozversion platform_changeset: 9189a2cc39d0c35e8823349aa0b4057ab8167b32 16:13:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:13:18 INFO - mozversion platform_version: 50.0a1 16:13:18 INFO - using testdate: 1468278798 16:13:18 INFO - actual date: 1468278798 16:13:18 INFO - starting webserver on 'localhost:55595' 16:13:18 INFO - SUITE-START | Running 2 tests 16:13:18 INFO - TEST-START | dromaeo_css 16:13:18 INFO - operating with platform_type : linux_ 16:13:18 INFO - Initialising browser for dromaeo_css test... 16:13:18 INFO - TEST-INFO | started process 16305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3RLUtN/profile http://localhost:55595/getInfo.html) 16:13:23 INFO - PROCESS | 16305 | __metrics Screen width/height:1600/1200 16:13:23 INFO - PROCESS | 16305 | colorDepth:24 16:13:23 INFO - PROCESS | 16305 | Browser inner width/height: 1024/697 16:13:23 INFO - PROCESS | 16305 | __metrics 16:13:24 INFO - PROCESS | 16305 | 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" 16:13:24 INFO - PROCESS | 16305 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:13:25 INFO - TEST-INFO | 16305: exit 0 16:13:25 INFO - Browser initialized. 16:13:25 INFO - Running cycle 1/1 for dromaeo_css test... 16:13:25 INFO - TEST-INFO | started process 16390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3RLUtN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:37 INFO - PROCESS | 16390 | RSS: Main: 156999680 16:13:37 INFO - PROCESS | 16390 | 16:14:28 INFO - PROCESS | 16390 | 1468278868505 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] 16:16:36 INFO - PROCESS | 16390 | Cycle 1(1): loaded http://localhost:55595/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55595/tests/dromaeo/cssquery-ext.html) 16:16:37 INFO - PROCESS | 16390 | RSS: Main: 247304192 16:16:37 INFO - PROCESS | 16390 | 16:19:39 INFO - PROCESS | 16390 | Cycle 1(1): loaded http://localhost:55595/tests/dromaeo/cssquery-ext.html (next: http://localhost:55595/tests/dromaeo/cssquery-jquery.html) 16:19:40 INFO - PROCESS | 16390 | RSS: Main: 236826624 16:19:40 INFO - PROCESS | 16390 | 16:22:42 INFO - PROCESS | 16390 | Cycle 1(1): loaded http://localhost:55595/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55595/tests/dromaeo/cssquery-mootools.html) 16:22:42 INFO - PROCESS | 16390 | RSS: Main: 241344512 16:22:42 INFO - PROCESS | 16390 | 16:25:44 INFO - PROCESS | 16390 | Cycle 1(1): loaded http://localhost:55595/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55595/tests/dromaeo/cssquery-prototype.html) 16:25:45 INFO - PROCESS | 16390 | RSS: Main: 235220992 16:25:45 INFO - PROCESS | 16390 | 16:28:47 INFO - PROCESS | 16390 | Cycle 1(1): loaded http://localhost:55595/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55595/tests/dromaeo/cssquery-yui.html) 16:28:48 INFO - PROCESS | 16390 | RSS: Main: 206639104 16:28:48 INFO - PROCESS | 16390 | 16:31:50 INFO - PROCESS | 16390 | Cycle 1(1): loaded http://localhost:55595/tests/dromaeo/cssquery-yui.html (next: http://localhost:55595/tests/dromaeo/cssquery-dojo.html) 16:31:51 INFO - PROCESS | 16390 | RSS: Main: 319696896 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | __start_tp_report 16:31:51 INFO - PROCESS | 16390 | _x_x_mozilla_page_load 16:31:51 INFO - PROCESS | 16390 | _x_x_mozilla_page_load_details 16:31:51 INFO - PROCESS | 16390 | |i|pagename|runs| 16:31:51 INFO - PROCESS | 16390 | |0;dojo.html;3017.9547306790387;3051.496503076992;3081.3067059911523;3092.5206592978084;3101.441740486713;5201.323827902377;5274.709890956001;5286.682799032061;5336.759845806943;5355.544778693811;5355.143177091666;5365.248865158881;5407.945920540794;5409.864753381167;5457.072297709389;4862.683925544846;4889.902201955976;4952.182889823175;4967.677100988424;5054.671446355994;4958.652894297401;4975.228839529878;4999.925001124986;5021.497850214986;5031.018951304497;4796.928046079312;4836.05696889105;4848.078865015654;4910.238912968495;4917;4900.068986892479;4902.411710594716;4920.040592084545;4924.704517728912;4933.7286449245275;4894.461609222983;4926.975365123151;4932.556069953722;4945.950540494585;4972.5276098770555;5260.631755777097;5338.652987555796;5368.946310536884;5379.865503362408;5482.616216864821;5754.568407369422;5796.2464879565805;5808.2449281593545;5809.6804675742815;5810.767569297265;5845.2401187845735;5848.385919478436;5866.941330586682;5867.325257595388;5876.853078673024;5235.267062611238;5306.071437498423;5363.731813409314;5369.48989845964;5423.783048678088;5931.169636250928;5960.493358064527;5970.850728731816;5972.46247837688;5972.641641501524;5486.698231597221;5814.738336774855;5825.330087040044;5833.329167145833;5838.095095260242;5682.772689092472;5745.166950792195;5753.482186603226;5764.13537969308;5765.88468230633;5734.827955161351;5738.540916726652;5739.540836733051;5741.25363702716;5745.224394706661;5597.608167428322;5723.370429252862;5729.255196824386;5733.827985160452;5781.797637082682;4400.383946247528;4418.536053714313;4437.7781110944325;4453.621442177451;4479.238529450001;3932.960670393317;3939.212157568498;3942.9211415771524;3973.0464688475136;3981.442598036277;5258.789648414097;5292.179712144623;5357.732113394315;5408.702521361284;5417.918731219035;5761.740721667535;5771.826845194567;5829.038208695518;5830.82507524775;5845.678487683218;5414.675119492843;5437.102878025082;5446.537044351275;5453.045716999509;5454.836354909359;4859.586935110485;4942.233953737176;4955.231939049381;5014.849554513297;5070.543651071347;5958.910616340759;5971.970140149271;5973.432523910222;5973.611715238582;6017.398260174035;5787.623804452697;5798.420157984167;5805.245318108618;5812.622179558315;5816.04035334182;5772.740226689725;5931.169636251015;5962.55280853929;5969.880602388015;6009.819705408845;5718.1708652246025;5736.340320863154;5741.827745167651;5748.425157484218;5751.597388182954;5786.1899334094105;5850.151727999501;5859.648421094748;5862.530997520274;5865.853353666193;5491.093969494988;5608.691521966331;5651.43485651448;5675.148727690879;5686.260786097946;5660.85847853807;5663.66018038919;5668.858278543069;5670.09278515452;5670.773169073356;5433.049216387038;5595.860103497445;5650.350209725934;5668.03643380618;5670.971645141748;5658.066419040977;5691.715414229355;5703.62926409782;5749.195112684311;5799.217105690678;5567.610267281413;5578.525825304894;5583.469570390806;5585.916211256753;5593.356763972032 16:31:51 INFO - PROCESS | 16390 | |1;ext.html;591.6213623281101;608.3338744075237;624.825048986284;630.2122347066169;649.9155109835721;35693.286134277296;36881.26237475249;36982.63017369826;37012.44481332782;43051.78474107625;42473.78763106188;46822.29766553504;46910;46928.296075558894;46959.76520117403;8961.775955601097;9078.591463384131;9102.86345704815;9111.179993800557;9117.908820911773;8950.731478055668;8990.910090899106;9021.052789457111;9041.050689677588;9046.185843274105;9095.727128186165;9130.360874738772;9140.405873618227;9143.268538516902;9148.130927561871;8972.3270754693;9023;9070.773230669287;9084.046175151612;9088.272938164964;8902.910970890338;8964.282857371394;8971.506567138806;8973.730788076304;8974.057723938959;42447.36328955068;42738.786306068585;42829.143417131796;42842.35736463955;42856;3600.747947643666;3704.2035962267973;3726.1802403471193;3819.427085937132;4038.818253178614;33275.33449331;33696.83151584251;33845.49231761526;34442.31115377678;35364.11589710252;4481.282994720829;4516.218546524886;4551.68138230324;4594.081183763261;4676.929846052312;344467;348474;348760;354474;356976.2151189253;33378.3324333512;34199.4870076949;34989.125271867895;57679;58146.7092664534;36953.44569831455;37195.25609487795;37456.625433745314;37491.06272343158;37606.811965939996;15598.142102184489;15699.921500392426;15710.68578628421;15712;15713.68572628564;13376.130551514121;13413.128146670435;13424.597262082154;13473.326333683473;13481.056326057082;8187.4268801184535;8203.83592328162;8218.260356567938;8242.052164001097;8286.83426331482;3128.2335827722354;3181.650018497963;3207.5349074384085;3230.7415406767404;3253.430649636304;1038.2835843268122;1053.4206186597341;1112.0603090388609;1174.2484809721784;1177.3524561491145;32893.835530822194;33097.50353744696;33352.49971250433;33418.3316333672;33440;1096.528492748126;1120.3781901044836;1146.1633007904277;1156.2773266708307;1253.993730031344;17114.572135696708;17468.82531174672;17497;17519;17714;570722;573643;574429;575722;576250;59706.10440843391;61111.08333375003;61165.69417152975;61182;61226;1254.0657210378326;1349.6693310139078;1349.7165595224917;1390.2492653966744;1414.7382734194207;31952;34416.65583344134;34571.30857382889;34764.13089672779;34835.651643484256;54462.727686361315;55329.17006244829;55502;55534.72232638811;55575;544056;560399;560714;561147;562420;37340.06649833776;44196;44292.778536107115;45190.548094518635;45589.31616025696;50510.74744626253;51273.743631281606;51816;52222.21666675079;52232.73883630557;42371.7881410591;43491.347629786214;43541.78229108834;43675.56324436842;43985.56014439814;43039.784801075795;43149;43491.13017739691;43624;43996;42496.15007699891;43026.569734302255;43592.56407435885;43678.12643747044;43816;35193;37156.25687486289;37520;37945;38641.22717545577;27247.31881702894;27348.863255683595;27994.16017519478;28417;28467.145985619634 16:31:51 INFO - PROCESS | 16390 | |2;jquery.html;381.6603223131412;382.79096496439945;388.87944737131494;399.1060025542785;403.39289369498897;14737.48418805342;18333.266669333243;19058.809411905895;20339.389818305455;20378.184872605096;14434.566962991126;18513.259469621233;20107;20176.59646807063;20192.29326973556;5638.774449022034;5768.278965129359;5800.245968024151;5829.417058294179;5841.795537156179;4257.467816522934;5727.713614319288;5786.392428794979;6086.634801911878;6393.584417012879;6409.070684750708;6413.583117097397;6455.419012288893;6471.4175724184815;6474.870502589969;6250.937490625081;6404.1674582304;6411.3588641135975;6412.230532336102;6412.935870641304;5465.015807690573;5597;5654.038813401731;5667.943320566824;5668.68822214778;24374.512509749704;24798.132065377806;24813.50372992548;24878;25244.242672719847;2185.2570126157184;2437.512497500489;2505.5991041433285;2964.451576458362;3105.819788480386;14422.06256593318;14888.851111488962;15010.099394036393;15442.536723898302;15659.530214093595;1910.6083252933115;1934.332655233942;1978.5548251643409;2026.6858636911302;2064.2878207018553;85623.5718821402;87396;88539.11460885438;89778;90288.645670315;11038.964284821577;11126.332420054823;11292.604758833402;11351.659450216506;11555.480003399867;15245.23773811305;15317;15368.692626147415;15381.846181538041;15424.46014389491;8208.343332533503;8473.533955632376;8480.363972701949;8521.36089793268;8546.273566746771;7197.856042879113;8227.8354432911;8242.17578242171;8341.20758527939;8380.497170169809;4525.818967241339;4733.124371991193;4848.248521479177;4880.902381952341;4884.535969082932;1914.8468122550162;1934.9559420810574;1937.2347922570507;1958.4222654317011;1985.2852972929734;861.646724842824;894.592960203106;929.7489677786959;952.3619175152664;982.8280050991048;18727.563515367012;20009.199632014846;20201.090950907244;20262.088206030763;20366.592668146553;952.3524003677566;977.5503268496429;981.3375971219398;987.4963768478128;1012.367270455965;10952.835707464394;11159.19920894814;11195.384253866116;11270.21108522412;11364.318140911571;183080;480083;504613;529302;531654;89834.55082724545;91106;92123;92900;95388.52305738427;1008.5209525475341;1011.8886922438526;1013.7414959185508;1014.9289549731448;1023.2121266624657;17920.193591288164;17943.102844857967;18333.908330458264;18445.16996735177;18483.815161848746;43810.34284485799;44557;44752.55247447484;45400;45888;58858.41141588529;60397.69801150966;60656.6967165179;61979.38020619736;62273.68863155836;8734;8858.955705221433;8878.778030549029;8897.28821694276;9092.727218183201;10167.430735499067;14026.158430494203;14061.437542498186;14332.641683957985;14511.1293322401;11911.940440297743;12047.698807529881;12401.565945191875;12464.937675311565;12561.309127998047;11409.543618255177;11941.402929853299;12005.699857503632;12185.147039707135;12486.37568121572;12508.93745531303;12580.182288151242;12638.178518396275;12820;12917.870821291666;8774.649014039622;8808.86786698213;8848;8867.113288671082;9119;9270.860937086083;12218.389080546114;12408.937955310166;12549.623511294676;12645.93677031609 16:31:51 INFO - PROCESS | 16390 | |3;mootools.html;1299.0776548650458;1451.3178805961202;1504.0975414751151;1576.8028996375454;1771.38887083956;8462.619182136803;8927.241184499317;8972.95513522433;9188.954055229715;9194.816103677938;8888.866666999984;9063.093690630934;9069.773255668595;9078.77303067422;9080.182783549479;2335.789778919897;2353.470469144437;2354.9175778847743;2357.3752955466816;2367.242482405631;2334.159702507096;2382.4758553118286;2386.343755467252;2387.8447900886385;2390.916317928873;2301.2405906050963;2305.8385912986096;2334.719833619968;2338.8596684199;2341.555104530142;2038.7349644546114;2072.181488312116;2074.2532688232222;2083.645780217359;2084.5622419291967;2237.2393386248755;2239.6080685879906;2246.809021233197;2247.112390605718;2247.1685476373846;8779.736607901774;8980.326475514363;9002.684906028324;9030.638774449015;9144.359894807367;5228.633995620308;5306.09796334624;5325.920111198335;5361.195820626898;5364.865878353034;3462.6191118976894;3464.566929133858;3502.474628805674;3515.1915059536195;3520.700740437066;1177.2583272538332;1181.533294348736;1190.2798806721983;1192.749522600255;1200.2438463767858;11880.108991825648;11965.401729913556;12029.759404811943;12037.518499259932;12061.216020958609;4240.597143271384;4297.613214810682;4401.867943961686;4408.625266852289;4475.060237350193;9707.563159657833;9710.320277580642;9711.757206069904;9718.902810971942;9788.9021109788;5107.361579802525;5114.846554603368;5125.846224613268;5144.331236939174;5147.948520514822;4202.537720850704;4631.212693842055;4800.975995120002;4849.4908034655855;4872.4153101628035;3819.6371344722206;3845.2309538092495;3889.78606176663;3894.8636797711943;3951.5258169019326;1126.743680795923;1143.1826244235244;1149.184079303687;1155.1971379891063;1161.1059484197121;774.3379410603852;967.6806653804227;1003.9799204016025;1011.9038691324313;1012.6253286283975;5982.760689572456;6157.322694503601;6275.843103922439;6307.337729538424;6464.030395440627;458.7041358323929;460.1073916601849;484.94180698316427;493.2502596053978;657.7699701558022;4135.276326642826;4401.559844015573;4404.273294906368;4410.580994805488;4413.227685155085;10608.522616482276;11014.944925275482;11035.117190624731;11108;11118.833217501744;10618.203634727426;10950.835737463944;10955.616553420752;10965.61620343284;11003.559857605605;8242.752717418487;8445.873311900326;8557;8609.22516973463;8617.655293788304;6253.843653908689;6411.8717625646295;6467.644279564669;6517.9348206517325;6566.113574667359;10404.739881503023;10561.313514621525;10582.894171058499;10645.94677026641;10658.946705266424;10087.546060427152;10472.371657700562;10476.476176191007;10701;10795.352278863293;7376.225496322809;8338.583070846555;8493.532855692996;8512.40413171072;8676.609552570275;8882.378233523592;8892.244159246537;8956.328275379503;8959.73120806377;8968.05835387275;7539.132999705104;7560.773176804704;7780.727674531364;7951.960240198762;8099.8380032397845;7770.689172433039;7827.373810095065;7979.84040319202;8142.154498028279;8145.266925976691;7098.396636285974;7142.964285178541;7197.928020719726;7202.099737532809;7202.135743710788;5442.482964118402;5483.862903427446;5511.669299842022;5566.638168519034;5611.410801865745;5127.307813445137;5175.378954525481;5252.7898884045735;5276.445973172762;5493.09363954943 16:31:51 INFO - PROCESS | 16390 | |4;prototype.html;318.47610680430694;328.8996855958933;333.8814720774125;334.56673607678056;339.46195280480447;5161.793528258865;5290.470952904708;5371.301730775003;5391.460853914607;5430.321209848769;5290.153575427932;5305.946940530604;5309.522143007129;5316.867078323044;5330.253764472977;4194.538600753915;4194.664426845845;4199.643030342425;4212.136512015029;4223.767692776896;3972.768441783042;4040.313146765057;4193.391958166064;4213.5997080277475;4231.301835197189;4190.476190476191;4193.245215861144;4203.180379825935;4209.579042095781;4237.131388065454;4175.91648167035;4179.644730197937;4185.414042034103;4190.685698572619;4212.957870421318;3604.441311596707;3710.128119891823;3765.5293088363956;3775.3959366501504;3777.9055523611855;5079.314292570492;5094.1849304111165;5113.054084994288;5171.7672704728375;5173.974130129325;4689.109069276827;4709.929351059737;4721.976390118062;4731.793392684844;4757.1437141314545;5321.840344789663;5482.5888058395385;5522.806701765419;5523.547782635853;5541.33503979521;3984.183242435294;4033.475648165749;4034.688726163979;4041.6766658667234;4063.553009168989;5756.194132821408;5761.9135712964335;5765.3369862465515;5781.248437703071;5786.276715410616;4008.579099194601;4186.434831297735;4188.32986722123;4191.7484950903045;4199.790010499462;4162.646175075092;4198.223328684203;4260.595243451867;4262.573742625713;4277.529471758104;4714.727023703581;4744.691595046311;4757.405324334458;4767.856964291077;4769.475357710649;5433.6196466247775;5529.30883639545;5547.306586676666;5588.636738611977;5602.13166959122;1436.9281535923162;1446.7323545144181;1454.5709015840353;1455.3087283540444;1461.5688371930305;930.9759264808791;943.9858402123974;966.0291407135924;970.9125779127422;973.493783232715;4713.421003963642;4718.457377401574;4751.121042607128;4761.286196766528;4779.30700048498;4949.282354058641;4956.3804524434445;4983.277424773388;5001.699898006132;5015.319867844313;4494.505604383515;4571.3600095986585;4695.812167513262;4708.105459962596;4746.288056791509;2663.4673065387;2672.7438104091434;2676.839389636628;2679.9364489343097;2686.3821321096257;5127.769250383742;5152.690838549699;5163.586913047023;5170.379554453414;5180.171172612364;5113.565346945551;5128.102582048201;5168.191133103459;5406.216098665672;5829.038208695518;5820.708964551755;5855.9121613176685;5881.5882888198275;5891.145783861316;5904.1734157218025;5686.346070201815;5749.626274292157;5759.539236861125;5767.596268261182;5772.5381969441505;5523.420040895809;5612.540739407702;5619.775208991595;5649.971750141223;5681.460261275172;5886.970565147147;5907.409259074058;5913.2608423947;5915.93513167634;5955.880882382415;3911.882643520699;4097.221527909687;4106.691998100095;4157.376393540993;4193.937090943699;5636.718164091697;5639.407862174413;5652.576056795675;5680.545556355399;5704.971475142598;5522.917156242579;5547.944520554743;5600.215969764155;5611.915821262603;5621.971890140523;5309.938012397459;5605.383407825218;5609.429359779268;5616.241807355955;5617.129344951539;5584.6492104736835;5593.133064375028;5594.86012849682;5600.7199640017025;5675.829725108253;5273.103572392626;5566.972165139148;5616.798424897769;5715.371309156073;5726.541876649775;5420.214068960057;5451.509364157245;5459.7270136492225;5469.535089517435;5486.670799752028 16:31:51 INFO - PROCESS | 16390 | |5;yui.html;746.6266866566716;802.3621221129201;806.0931452116373;833.654033576066;860.9827803443933;14921.626959326022;15491.225438728075;15493;15529;15542.53372398827;17330.48008559742;17341.306347746107;17461.650766984654;17539.82460175398;17559.91220043898;669.3741351836024;799.2367289238763;812.4556547113418;814.9633266502992;817.979550511239;827.2472050434106;827.2472050434106;827.3711978896052;828.031203491913;832.9795999900105;859.3941271403677;862.3144600042981;865.6364326982651;866.2680035370103;866.3762091294258;694.7429451103073;831.2186544648011;857.6826574167535;859.2009431228954;860.5009094725044;608.3429895712663;613.2273335597135;613.6164896939413;613.9355367686418;613.9938600613981;15702.214889255605;15715.214239288105;15715.528534143994;15723.056616602926;15734.449294274646;13887.236202008886;14172.787408188886;14214.715705685829;14651.487197948127;14822.851771482256;13634.250116243602;13788.172709637354;13906.652333691638;13944.860551394559;14000.369983350774;4543.727376357429;4578.565036321577;4651.186042442559;4729.881752956169;4732.763361831895;60091.39908600902;60776.69611651913;82823.58588207081;84277.15722842755;86100.56949715274;11413.714657133472;11596.884031159749;11650.6504804856;11689.123315751352;11790.410479475991;21473.355799326044;21807.45481362947;21809.127634894743;21850;21851.125954961943;8452.492850428995;8473.195046470575;8654.35092368075;8759.868601970975;8796.560171991376;4643.141018911512;4709.882252943636;4716.882077948078;4766.547178018082;4780.043991201704;3399.184195792992;3510.350585141757;3511.367953768346;3516.4725291206523;3525.25969546393;615.7013848283578;619.0961196652937;625.9624422534663;631.1353445779312;631.2488139114439;578.6817250512202;625.1810128731377;676.7225437570572;681.465049935798;688.18793823288;9690.273229507815;9706.854397184048;10145.644902428527;10374.896251037544;10485.633002845016;621.5276389943675;650.4536189600664;684.5276759036249;687.71116131225;692.8995295682098;4454.837065683401;4668.929966050512;4685.742284174335;4785.617150627942;4861.708297502161;117461;123217.38391307986;124449.37775311247;126132;127319.36340318239;64554;108947.45526272318;109204;109257.45371273093;111637;62690.99999999909;92799;95138.0486195143;95558;98479;9732.854007189757;10079.848802267825;10217.489125543842;10263.846042309222;10520.473976301308;44653.77673111614;50054.49945500498;53377.73311133419;54196;56659.716701416226;82689;87073.56463217644;89946;90024;91003.54498227731;11230.382328971986;13965.720685586435;14002.929985350009;14093.788593170906;14349.784753228501;15555.611109722347;15932;16347.182640867672;16747.581310467336;16882.493525194262;10859.857997858408;13962.441502339792;15036.774448383501;15619.84380156184;15630.765538516704;14980.625484362978;15184.46854360092;15823;16313.26590303463;16331.59171020734;14881.553553393416;14889.255537223313;14900.561295127418;15353.769693454384;16177.4337898173;11574.6527604172;11594.942025290156;11644.592439264585;11665.59170429031;11950.880491195325;12103.455344509694;12249.510019599116;12489.687757805765;12649.177803442746;12721.236725796482 16:31:51 INFO - PROCESS | 16390 | __end_tp_report 16:31:51 INFO - PROCESS | 16390 | __start_cc_report 16:31:51 INFO - PROCESS | 16390 | _x_x_mozilla_cycle_collect,756 16:31:51 INFO - PROCESS | 16390 | __end_cc_report 16:31:51 INFO - PROCESS | 16390 | __startTimestamp1468279911198__endTimestamp 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | ------- Summary: start ------- 16:31:51 INFO - PROCESS | 16390 | Number of tests: 6 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | [#0] dojo.html Cycles:165 Average:5367.92 Median:5593.36 stddev:617.73 (11.0%) stddev-sans-first:591.47 16:31:51 INFO - PROCESS | 16390 | Values: 3018.0 3051.5 3081.3 3092.5 3101.4 5201.3 5274.7 5286.7 5336.8 5355.5 5355.1 5365.2 5407.9 5409.9 5457.1 4862.7 4889.9 4952.2 4967.7 5054.7 4958.7 4975.2 4999.9 5021.5 5031.0 4796.9 4836.1 4848.1 4910.2 4917.0 4900.1 4902.4 4920.0 4924.7 4933.7 4894.5 4927.0 4932.6 4946.0 4972.5 5260.6 5338.7 5368.9 5379.9 5482.6 5754.6 5796.2 5808.2 5809.7 5810.8 5845.2 5848.4 5866.9 5867.3 5876.9 5235.3 5306.1 5363.7 5369.5 5423.8 5931.2 5960.5 5970.9 5972.5 5972.6 5486.7 5814.7 5825.3 5833.3 5838.1 5682.8 5745.2 5753.5 5764.1 5765.9 5734.8 5738.5 5739.5 5741.3 5745.2 5597.6 5723.4 5729.3 5733.8 5781.8 4400.4 4418.5 4437.8 4453.6 4479.2 3933.0 3939.2 3942.9 3973.0 3981.4 5258.8 5292.2 5357.7 5408.7 5417.9 5761.7 5771.8 5829.0 5830.8 5845.7 5414.7 5437.1 5446.5 5453.0 5454.8 4859.6 4942.2 4955.2 5014.8 5070.5 5958.9 5972.0 5973.4 5973.6 6017.4 5787.6 5798.4 5805.2 5812.6 5816.0 5772.7 5931.2 5962.6 5969.9 6009.8 5718.2 5736.3 5741.8 5748.4 5751.6 5786.2 5850.2 5859.6 5862.5 5865.9 5491.1 5608.7 5651.4 5675.1 5686.3 5660.9 5663.7 5668.9 5670.1 5670.8 5433.0 5595.9 5650.4 5668.0 5671.0 5658.1 5691.7 5703.6 5749.2 5799.2 5567.6 5578.5 5583.5 5585.9 5593.4 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | [#1] ext.html Cycles:180 Average:64277.62 Median:42941.28 stddev:134503.66 (313.2%) stddev-sans-first:134795.98 16:31:51 INFO - PROCESS | 16390 | Values: 591.6 608.3 624.8 630.2 649.9 35693.3 36881.3 36982.6 37012.4 43051.8 42473.8 46822.3 46910.0 46928.3 46959.8 8961.8 9078.6 9102.9 9111.2 9117.9 8950.7 8990.9 9021.1 9041.1 9046.2 9095.7 9130.4 9140.4 9143.3 9148.1 8972.3 9023.0 9070.8 9084.0 9088.3 8902.9 8964.3 8971.5 8973.7 8974.1 42447.4 42738.8 42829.1 42842.4 42856.0 3600.7 3704.2 3726.2 3819.4 4038.8 33275.3 33696.8 33845.5 34442.3 35364.1 4481.3 4516.2 4551.7 4594.1 4676.9 344467.0 348474.0 348760.0 354474.0 356976.2 33378.3 34199.5 34989.1 57679.0 58146.7 36953.4 37195.3 37456.6 37491.1 37606.8 15598.1 15699.9 15710.7 15712.0 15713.7 13376.1 13413.1 13424.6 13473.3 13481.1 8187.4 8203.8 8218.3 8242.1 8286.8 3128.2 3181.7 3207.5 3230.7 3253.4 1038.3 1053.4 1112.1 1174.2 1177.4 32893.8 33097.5 33352.5 33418.3 33440.0 1096.5 1120.4 1146.2 1156.3 1254.0 17114.6 17468.8 17497.0 17519.0 17714.0 570722.0 573643.0 574429.0 575722.0 576250.0 59706.1 61111.1 61165.7 61182.0 61226.0 1254.1 1349.7 1349.7 1390.2 1414.7 31952.0 34416.7 34571.3 34764.1 34835.7 54462.7 55329.2 55502.0 55534.7 55575.0 544056.0 560399.0 560714.0 561147.0 562420.0 37340.1 44196.0 44292.8 45190.5 45589.3 50510.7 51273.7 51816.0 52222.2 52232.7 42371.8 43491.3 43541.8 43675.6 43985.6 43039.8 43149.0 43491.1 43624.0 43996.0 42496.2 43026.6 43592.6 43678.1 43816.0 35193.0 37156.3 37520.0 37945.0 38641.2 27247.3 27348.9 27994.2 28417.0 28467.1 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | [#2] jquery.html Cycles:180 Average:28505.39 Median:1743.74 stddev:77158.97 (4424.9%) stddev-sans-first:77346.53 16:31:51 INFO - PROCESS | 16390 | Values: 381.7 382.8 388.9 399.1 403.4 14737.5 18333.3 19058.8 20339.4 20378.2 14434.6 18513.3 20107.0 20176.6 20192.3 5638.8 5768.3 5800.2 5829.4 5841.8 4257.5 5727.7 5786.4 6086.6 6393.6 6409.1 6413.6 6455.4 6471.4 6474.9 6250.9 6404.2 6411.4 6412.2 6412.9 5465.0 5597.0 5654.0 5667.9 5668.7 24374.5 24798.1 24813.5 24878.0 25244.2 2185.3 2437.5 2505.6 2964.5 3105.8 14422.1 14888.9 15010.1 15442.5 15659.5 1910.6 1934.3 1978.6 2026.7 2064.3 85623.6 87396.0 88539.1 89778.0 90288.6 11039.0 11126.3 11292.6 11351.7 11555.5 15245.2 15317.0 15368.7 15381.8 15424.5 8208.3 8473.5 8480.4 8521.4 8546.3 7197.9 8227.8 8242.2 8341.2 8380.5 4525.8 4733.1 4848.2 4880.9 4884.5 1914.8 1935.0 1937.2 1958.4 1985.3 861.6 894.6 929.7 952.4 982.8 18727.6 20009.2 20201.1 20262.1 20366.6 952.4 977.6 981.3 987.5 1012.4 10952.8 11159.2 11195.4 11270.2 11364.3 183080.0 480083.0 504613.0 529302.0 531654.0 89834.6 91106.0 92123.0 92900.0 95388.5 1008.5 1011.9 1013.7 1014.9 1023.2 17920.2 17943.1 18333.9 18445.2 18483.8 43810.3 44557.0 44752.6 45400.0 45888.0 58858.4 60397.7 60656.7 61979.4 62273.7 8734.0 8859.0 8878.8 8897.3 9092.7 10167.4 14026.2 14061.4 14332.6 14511.1 11911.9 12047.7 12401.6 12464.9 12561.3 11409.5 11941.4 12005.7 12185.1 12486.4 12508.9 12580.2 12638.2 12820.0 12917.9 8774.6 8808.9 8848.0 8867.1 9119.0 9270.9 12218.4 12408.9 12549.6 12645.9 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | [#3] mootools.html Cycles:180 Average:5879.15 Median:2545.66 stddev:3373.06 (132.5%) stddev-sans-first:3364.96 16:31:51 INFO - PROCESS | 16390 | Values: 1299.1 1451.3 1504.1 1576.8 1771.4 8462.6 8927.2 8973.0 9189.0 9194.8 8888.9 9063.1 9069.8 9078.8 9080.2 2335.8 2353.5 2354.9 2357.4 2367.2 2334.2 2382.5 2386.3 2387.8 2390.9 2301.2 2305.8 2334.7 2338.9 2341.6 2038.7 2072.2 2074.3 2083.6 2084.6 2237.2 2239.6 2246.8 2247.1 2247.2 8779.7 8980.3 9002.7 9030.6 9144.4 5228.6 5306.1 5325.9 5361.2 5364.9 3462.6 3464.6 3502.5 3515.2 3520.7 1177.3 1181.5 1190.3 1192.7 1200.2 11880.1 11965.4 12029.8 12037.5 12061.2 4240.6 4297.6 4401.9 4408.6 4475.1 9707.6 9710.3 9711.8 9718.9 9788.9 5107.4 5114.8 5125.8 5144.3 5147.9 4202.5 4631.2 4801.0 4849.5 4872.4 3819.6 3845.2 3889.8 3894.9 3951.5 1126.7 1143.2 1149.2 1155.2 1161.1 774.3 967.7 1004.0 1011.9 1012.6 5982.8 6157.3 6275.8 6307.3 6464.0 458.7 460.1 484.9 493.3 657.8 4135.3 4401.6 4404.3 4410.6 4413.2 10608.5 11014.9 11035.1 11108.0 11118.8 10618.2 10950.8 10955.6 10965.6 11003.6 8242.8 8445.9 8557.0 8609.2 8617.7 6253.8 6411.9 6467.6 6517.9 6566.1 10404.7 10561.3 10582.9 10645.9 10658.9 10087.5 10472.4 10476.5 10701.0 10795.4 7376.2 8338.6 8493.5 8512.4 8676.6 8882.4 8892.2 8956.3 8959.7 8968.1 7539.1 7560.8 7780.7 7952.0 8099.8 7770.7 7827.4 7979.8 8142.2 8145.3 7098.4 7143.0 7197.9 7202.1 7202.1 5442.5 5483.9 5511.7 5566.6 5611.4 5127.3 5175.4 5252.8 5276.4 5493.1 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | [#4] prototype.html Cycles:180 Average:4607.32 Median:5140.40 stddev:1342.01 (26.1%) stddev-sans-first:1306.60 16:31:51 INFO - PROCESS | 16390 | Values: 318.5 328.9 333.9 334.6 339.5 5161.8 5290.5 5371.3 5391.5 5430.3 5290.2 5305.9 5309.5 5316.9 5330.3 4194.5 4194.7 4199.6 4212.1 4223.8 3972.8 4040.3 4193.4 4213.6 4231.3 4190.5 4193.2 4203.2 4209.6 4237.1 4175.9 4179.6 4185.4 4190.7 4213.0 3604.4 3710.1 3765.5 3775.4 3777.9 5079.3 5094.2 5113.1 5171.8 5174.0 4689.1 4709.9 4722.0 4731.8 4757.1 5321.8 5482.6 5522.8 5523.5 5541.3 3984.2 4033.5 4034.7 4041.7 4063.6 5756.2 5761.9 5765.3 5781.2 5786.3 4008.6 4186.4 4188.3 4191.7 4199.8 4162.6 4198.2 4260.6 4262.6 4277.5 4714.7 4744.7 4757.4 4767.9 4769.5 5433.6 5529.3 5547.3 5588.6 5602.1 1436.9 1446.7 1454.6 1455.3 1461.6 931.0 944.0 966.0 970.9 973.5 4713.4 4718.5 4751.1 4761.3 4779.3 4949.3 4956.4 4983.3 5001.7 5015.3 4494.5 4571.4 4695.8 4708.1 4746.3 2663.5 2672.7 2676.8 2679.9 2686.4 5127.8 5152.7 5163.6 5170.4 5180.2 5113.6 5128.1 5168.2 5406.2 5829.0 5820.7 5855.9 5881.6 5891.1 5904.2 5686.3 5749.6 5759.5 5767.6 5772.5 5523.4 5612.5 5619.8 5650.0 5681.5 5887.0 5907.4 5913.3 5915.9 5955.9 3911.9 4097.2 4106.7 4157.4 4193.9 5636.7 5639.4 5652.6 5680.5 5705.0 5522.9 5547.9 5600.2 5611.9 5622.0 5309.9 5605.4 5609.4 5616.2 5617.1 5584.6 5593.1 5594.9 5600.7 5675.8 5273.1 5567.0 5616.8 5715.4 5726.5 5420.2 5451.5 5459.7 5469.5 5486.7 16:31:51 INFO - PROCESS | 16390 | 16:31:51 INFO - PROCESS | 16390 | [#5] yui.html Cycles:180 Average:21958.19 Median:3990.05 stddev:31849.84 (798.2%) stddev-sans-first:31899.36 16:31:51 INFO - PROCESS | 16390 | Values: 746.6 802.4 806.1 833.7 861.0 14921.6 15491.2 15493.0 15529.0 15542.5 17330.5 17341.3 17461.7 17539.8 17559.9 669.4 799.2 812.5 815.0 818.0 827.2 827.2 827.4 828.0 833.0 859.4 862.3 865.6 866.3 866.4 694.7 831.2 857.7 859.2 860.5 608.3 613.2 613.6 613.9 614.0 15702.2 15715.2 15715.5 15723.1 15734.4 13887.2 14172.8 14214.7 14651.5 14822.9 13634.3 13788.2 13906.7 13944.9 14000.4 4543.7 4578.6 4651.2 4729.9 4732.8 60091.4 60776.7 82823.6 84277.2 86100.6 11413.7 11596.9 11650.7 11689.1 11790.4 21473.4 21807.5 21809.1 21850.0 21851.1 8452.5 8473.2 8654.4 8759.9 8796.6 4643.1 4709.9 4716.9 4766.5 4780.0 3399.2 3510.4 3511.4 3516.5 3525.3 615.7 619.1 626.0 631.1 631.2 578.7 625.2 676.7 681.5 688.2 9690.3 9706.9 10145.6 10374.9 10485.6 621.5 650.5 684.5 687.7 692.9 4454.8 4668.9 4685.7 4785.6 4861.7 117461.0 123217.4 124449.4 126132.0 127319.4 64554.0 108947.5 109204.0 109257.5 111637.0 62691.0 92799.0 95138.0 95558.0 98479.0 9732.9 10079.8 10217.5 10263.8 10520.5 44653.8 50054.5 53377.7 54196.0 56659.7 82689.0 87073.6 89946.0 90024.0 91003.5 11230.4 13965.7 14002.9 14093.8 14349.8 15555.6 15932.0 16347.2 16747.6 16882.5 10859.9 13962.4 15036.8 15619.8 15630.8 14980.6 15184.5 15823.0 16313.3 16331.6 14881.6 14889.3 14900.6 15353.8 16177.4 11574.7 11594.9 11644.6 11665.6 11950.9 12103.5 12249.5 12489.7 12649.2 12721.2 16:31:51 INFO - PROCESS | 16390 | -------- Summary: end -------- 16:31:51 INFO - PROCESS | 16390 | 16:31:52 INFO - TEST-INFO | 16390: exit 0 16:31:52 INFO - TEST-OK | dromaeo_css | took 1113120ms 16:31:52 INFO - TEST-START | kraken 16:31:52 INFO - operating with platform_type : linux_ 16:31:52 INFO - Initialising browser for kraken test... 16:31:52 INFO - TEST-INFO | started process 16581 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuXHvO5/profile http://localhost:55595/getInfo.html) 16:31:56 INFO - PROCESS | 16581 | __metrics Screen width/height:1600/1200 16:31:56 INFO - PROCESS | 16581 | colorDepth:24 16:31:56 INFO - PROCESS | 16581 | Browser inner width/height: 1024/697 16:31:56 INFO - PROCESS | 16581 | __metrics 16:31:57 INFO - PROCESS | 16581 | 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" 16:31:57 INFO - PROCESS | 16581 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:31:57 INFO - PROCESS | 16581 | console.error: PushService: 16:31:57 INFO - PROCESS | 16581 | stateChangeProcessEnqueue: Error transitioning state 16:31:57 INFO - PROCESS | 16581 | UnknownError 16:31:58 INFO - TEST-INFO | 16581: exit 0 16:31:58 INFO - Browser initialized. 16:31:58 INFO - Running cycle 1/1 for kraken test... 16:31:58 INFO - TEST-INFO | started process 16667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuXHvO5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:09 INFO - PROCESS | 16667 | RSS: Main: 157360128 16:32:09 INFO - PROCESS | 16667 | 16:32:12 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55595/tests/kraken/driver.html?testName=audio-beat-detection) 16:32:13 INFO - PROCESS | 16667 | RSS: Main: 215302144 16:32:13 INFO - PROCESS | 16667 | 16:32:15 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55595/tests/kraken/driver.html?testName=audio-dft) 16:32:16 INFO - PROCESS | 16667 | RSS: Main: 203980800 16:32:16 INFO - PROCESS | 16667 | 16:32:19 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55595/tests/kraken/driver.html?testName=audio-fft) 16:32:20 INFO - PROCESS | 16667 | RSS: Main: 269684736 16:32:20 INFO - PROCESS | 16667 | 16:32:22 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55595/tests/kraken/driver.html?testName=audio-oscillator) 16:32:22 INFO - PROCESS | 16667 | RSS: Main: 180576256 16:32:22 INFO - PROCESS | 16667 | 16:32:25 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55595/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:32:25 INFO - PROCESS | 16667 | RSS: Main: 184918016 16:32:25 INFO - PROCESS | 16667 | 16:32:28 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55595/tests/kraken/driver.html?testName=imaging-darkroom) 16:32:29 INFO - PROCESS | 16667 | RSS: Main: 218898432 16:32:29 INFO - PROCESS | 16667 | 16:32:32 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55595/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:33 INFO - PROCESS | 16667 | RSS: Main: 220925952 16:32:33 INFO - PROCESS | 16667 | 16:32:36 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55595/tests/kraken/driver.html?testName=json-parse-financial) 16:32:36 INFO - PROCESS | 16667 | RSS: Main: 220975104 16:32:36 INFO - PROCESS | 16667 | 16:32:38 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55595/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:38 INFO - PROCESS | 16667 | RSS: Main: 207556608 16:32:38 INFO - PROCESS | 16667 | 16:32:40 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:41 INFO - PROCESS | 16667 | RSS: Main: 212303872 16:32:41 INFO - PROCESS | 16667 | 16:32:43 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:43 INFO - PROCESS | 16667 | RSS: Main: 210468864 16:32:43 INFO - PROCESS | 16667 | 16:32:46 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:47 INFO - PROCESS | 16667 | RSS: Main: 224968704 16:32:47 INFO - PROCESS | 16667 | 16:32:49 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:50 INFO - PROCESS | 16667 | RSS: Main: 224088064 16:32:50 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | Cycle 1(1): loaded http://localhost:55595/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55595/tests/kraken/driver.html?testName=ai-astar) 16:32:52 INFO - PROCESS | 16667 | RSS: Main: 266924032 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | __start_tp_report 16:32:52 INFO - PROCESS | 16667 | _x_x_mozilla_page_load 16:32:52 INFO - PROCESS | 16667 | _x_x_mozilla_page_load_details 16:32:52 INFO - PROCESS | 16667 | |i|pagename|runs| 16:32:52 INFO - PROCESS | 16667 | |0;ai-astar;103;114;111;110;112;111;113;111;112;111 16:32:52 INFO - PROCESS | 16667 | |1;audio-beat-detection;149;117;120;172;117;119;148;118;167;116 16:32:52 INFO - PROCESS | 16667 | |2;audio-dft;160;161;162;162;161;161;162;162;163;162 16:32:52 INFO - PROCESS | 16667 | |3;audio-fft;75;76;76;125;75;76;75;75;105;73 16:32:52 INFO - PROCESS | 16667 | |4;audio-oscillator;83;83;119;84;85;85;114;83;84;121 16:32:52 INFO - PROCESS | 16667 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;117 16:32:52 INFO - PROCESS | 16667 | |6;imaging-darkroom;146;146;147;146;146;146;145;145;146;145 16:32:52 INFO - PROCESS | 16667 | |7;imaging-desaturate;95;96;95;95;95;96;95;95;95;95 16:32:52 INFO - PROCESS | 16667 | |8;json-parse-financial;59;58;59;55;55;55;56;56;56;56 16:32:52 INFO - PROCESS | 16667 | |9;json-stringify-tinderbox;62;62;63;62;63;63;65;64;63;63 16:32:52 INFO - PROCESS | 16667 | |10;stanford-crypto-aes;75;75;74;75;75;76;74;75;74;78 16:32:52 INFO - PROCESS | 16667 | |11;stanford-crypto-ccm;129;129;127;119;122;124;120;142;167;118 16:32:52 INFO - PROCESS | 16667 | |12;stanford-crypto-pbkdf2;169;161;164;159;164;170;161;161;161;167 16:32:52 INFO - PROCESS | 16667 | |13;stanford-crypto-sha256-iterative;65;66;63;63;63;63;63;64;63;62 16:32:52 INFO - PROCESS | 16667 | __end_tp_report 16:32:52 INFO - PROCESS | 16667 | __start_cc_report 16:32:52 INFO - PROCESS | 16667 | _x_x_mozilla_cycle_collect,1656 16:32:52 INFO - PROCESS | 16667 | __end_cc_report 16:32:52 INFO - PROCESS | 16667 | __startTimestamp1468279972280__endTimestamp 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | ------- Summary: start ------- 16:32:52 INFO - PROCESS | 16667 | Number of tests: 14 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:2.97 (2.7%) stddev-sans-first:1.22 16:32:52 INFO - PROCESS | 16667 | Values: 103.0 114.0 111.0 110.0 112.0 111.0 113.0 111.0 112.0 111.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:134.00 stddev:22.44 (16.7%) stddev-sans-first:23.16 16:32:52 INFO - PROCESS | 16667 | Values: 149.0 117.0 120.0 172.0 117.0 119.0 148.0 118.0 167.0 116.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.67 16:32:52 INFO - PROCESS | 16667 | Values: 160.0 161.0 162.0 162.0 161.0 161.0 162.0 162.0 163.0 162.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.00 stddev:17.48 (23.3%) stddev-sans-first:18.30 16:32:52 INFO - PROCESS | 16667 | Values: 75.0 76.0 76.0 125.0 75.0 76.0 75.0 75.0 105.0 73.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:83.50 stddev:16.60 (19.9%) stddev-sans-first:17.11 16:32:52 INFO - PROCESS | 16667 | Values: 83.0 83.0 119.0 84.0 85.0 85.0 114.0 83.0 84.0 121.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:32:52 INFO - PROCESS | 16667 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 16:32:52 INFO - PROCESS | 16667 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:32:52 INFO - PROCESS | 16667 | Values: 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.00 stddev:1.58 (2.8%) stddev-sans-first:1.39 16:32:52 INFO - PROCESS | 16667 | Values: 59.0 58.0 59.0 55.0 55.0 55.0 56.0 56.0 56.0 56.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.94 (1.5%) stddev-sans-first:0.93 16:32:52 INFO - PROCESS | 16667 | Values: 62.0 62.0 63.0 62.0 63.0 63.0 65.0 64.0 63.0 63.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:1.20 (1.6%) stddev-sans-first:1.27 16:32:52 INFO - PROCESS | 16667 | Values: 75.0 75.0 74.0 75.0 75.0 76.0 74.0 75.0 74.0 78.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:128.00 stddev:14.86 (11.6%) stddev-sans-first:15.76 16:32:52 INFO - PROCESS | 16667 | Values: 129.0 129.0 127.0 119.0 122.0 124.0 120.0 142.0 167.0 118.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.70 Median:164.00 stddev:3.80 (2.3%) stddev-sans-first:3.52 16:32:52 INFO - PROCESS | 16667 | Values: 169.0 161.0 164.0 159.0 164.0 170.0 161.0 161.0 161.0 167.0 16:32:52 INFO - PROCESS | 16667 | 16:32:52 INFO - PROCESS | 16667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:1.18 (1.9%) stddev-sans-first:1.12 16:32:52 INFO - PROCESS | 16667 | Values: 65.0 66.0 63.0 63.0 63.0 63.0 63.0 64.0 63.0 62.0 16:32:52 INFO - PROCESS | 16667 | -------- Summary: end -------- 16:32:52 INFO - PROCESS | 16667 | 16:32:53 INFO - TEST-INFO | 16667: exit 0 16:32:53 INFO - TEST-OK | kraken | took 61284ms 16:32:53 INFO - SUITE-END | took 1174s 16:32:53 INFO - Completed test suite (00:19:34) 16:32:53 INFO - javascript benchmark 16:32:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.9547306790387, 3051.496503076992, 3081.3067059911523, 3092.5206592978084, 3101.441740486713, 5201.323827902377, 5274.709890956001, 5286.682799032061, 5336.759845806943, 5355.544778693811, 5355.143177091666, 5365.248865158881, 5407.945920540794, 5409.864753381167, 5457.072297709389, 4862.683925544846, 4889.902201955976, 4952.182889823175, 4967.677100988424, 5054.671446355994, 4958.652894297401, 4975.228839529878, 4999.925001124986, 5021.497850214986, 5031.018951304497, 4796.928046079312, 4836.05696889105, 4848.078865015654, 4910.238912968495, 4917.0, 4900.068986892479, 4902.411710594716, 4920.040592084545, 4924.704517728912, 4933.7286449245275, 4894.461609222983, 4926.975365123151, 4932.556069953722, 4945.950540494585, 4972.5276098770555, 5260.631755777097, 5338.652987555796, 5368.946310536884, 5379.865503362408, 5482.616216864821, 5754.568407369422, 5796.2464879565805, 5808.2449281593545, 5809.6804675742815, 5810.767569297265, 5845.2401187845735, 5848.385919478436, 5866.941330586682, 5867.325257595388, 5876.853078673024, 5235.267062611238, 5306.071437498423, 5363.731813409314, 5369.48989845964, 5423.783048678088, 5931.169636250928, 5960.493358064527, 5970.850728731816, 5972.46247837688, 5972.641641501524, 5486.698231597221, 5814.738336774855, 5825.330087040044, 5833.329167145833, 5838.095095260242, 5682.772689092472, 5745.166950792195, 5753.482186603226, 5764.13537969308, 5765.88468230633, 5734.827955161351, 5738.540916726652, 5739.540836733051, 5741.25363702716, 5745.224394706661, 5597.608167428322, 5723.370429252862, 5729.255196824386, 5733.827985160452, 5781.797637082682, 4400.383946247528, 4418.536053714313, 4437.7781110944325, 4453.621442177451, 4479.238529450001, 3932.960670393317, 3939.212157568498, 3942.9211415771524, 3973.0464688475136, 3981.442598036277, 5258.789648414097, 5292.179712144623, 5357.732113394315, 5408.702521361284, 5417.918731219035, 5761.740721667535, 5771.826845194567, 5829.038208695518, 5830.82507524775, 5845.678487683218, 5414.675119492843, 5437.102878025082, 5446.537044351275, 5453.045716999509, 5454.836354909359, 4859.586935110485, 4942.233953737176, 4955.231939049381, 5014.849554513297, 5070.543651071347, 5958.910616340759, 5971.970140149271, 5973.432523910222, 5973.611715238582, 6017.398260174035, 5787.623804452697, 5798.420157984167, 5805.245318108618, 5812.622179558315, 5816.04035334182, 5772.740226689725, 5931.169636251015, 5962.55280853929, 5969.880602388015, 6009.819705408845, 5718.1708652246025, 5736.340320863154, 5741.827745167651, 5748.425157484218, 5751.597388182954, 5786.1899334094105, 5850.151727999501, 5859.648421094748, 5862.530997520274, 5865.853353666193, 5491.093969494988, 5608.691521966331, 5651.43485651448, 5675.148727690879, 5686.260786097946, 5660.85847853807, 5663.66018038919, 5668.858278543069, 5670.09278515452, 5670.773169073356, 5433.049216387038, 5595.860103497445, 5650.350209725934, 5668.03643380618, 5670.971645141748, 5658.066419040977, 5691.715414229355, 5703.62926409782, 5749.195112684311, 5799.217105690678, 5567.610267281413, 5578.525825304894, 5583.469570390806, 5585.916211256753, 5593.356763972032], "value": 5325.366775739663, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.6213623281101, 608.3338744075237, 624.825048986284, 630.2122347066169, 649.9155109835721, 35693.286134277296, 36881.26237475249, 36982.63017369826, 37012.44481332782, 43051.78474107625, 42473.78763106188, 46822.29766553504, 46910.0, 46928.296075558894, 46959.76520117403, 8961.775955601097, 9078.591463384131, 9102.86345704815, 9111.179993800557, 9117.908820911773, 8950.731478055668, 8990.910090899106, 9021.052789457111, 9041.050689677588, 9046.185843274105, 9095.727128186165, 9130.360874738772, 9140.405873618227, 9143.268538516902, 9148.130927561871, 8972.3270754693, 9023.0, 9070.773230669287, 9084.046175151612, 9088.272938164964, 8902.910970890338, 8964.282857371394, 8971.506567138806, 8973.730788076304, 8974.057723938959, 42447.36328955068, 42738.786306068585, 42829.143417131796, 42842.35736463955, 42856.0, 3600.747947643666, 3704.2035962267973, 3726.1802403471193, 3819.427085937132, 4038.818253178614, 33275.33449331, 33696.83151584251, 33845.49231761526, 34442.31115377678, 35364.11589710252, 4481.282994720829, 4516.218546524886, 4551.68138230324, 4594.081183763261, 4676.929846052312, 344467.0, 348474.0, 348760.0, 354474.0, 356976.2151189253, 33378.3324333512, 34199.4870076949, 34989.125271867895, 57679.0, 58146.7092664534, 36953.44569831455, 37195.25609487795, 37456.625433745314, 37491.06272343158, 37606.811965939996, 15598.142102184489, 15699.921500392426, 15710.68578628421, 15712.0, 15713.68572628564, 13376.130551514121, 13413.128146670435, 13424.597262082154, 13473.326333683473, 13481.056326057082, 8187.4268801184535, 8203.83592328162, 8218.260356567938, 8242.052164001097, 8286.83426331482, 3128.2335827722354, 3181.650018497963, 3207.5349074384085, 3230.7415406767404, 3253.430649636304, 1038.2835843268122, 1053.4206186597341, 1112.0603090388609, 1174.2484809721784, 1177.3524561491145, 32893.835530822194, 33097.50353744696, 33352.49971250433, 33418.3316333672, 33440.0, 1096.528492748126, 1120.3781901044836, 1146.1633007904277, 1156.2773266708307, 1253.993730031344, 17114.572135696708, 17468.82531174672, 17497.0, 17519.0, 17714.0, 570722.0, 573643.0, 574429.0, 575722.0, 576250.0, 59706.10440843391, 61111.08333375003, 61165.69417152975, 61182.0, 61226.0, 1254.0657210378326, 1349.6693310139078, 1349.7165595224917, 1390.2492653966744, 1414.7382734194207, 31952.0, 34416.65583344134, 34571.30857382889, 34764.13089672779, 34835.651643484256, 54462.727686361315, 55329.17006244829, 55502.0, 55534.72232638811, 55575.0, 544056.0, 560399.0, 560714.0, 561147.0, 562420.0, 37340.06649833776, 44196.0, 44292.778536107115, 45190.548094518635, 45589.31616025696, 50510.74744626253, 51273.743631281606, 51816.0, 52222.21666675079, 52232.73883630557, 42371.7881410591, 43491.347629786214, 43541.78229108834, 43675.56324436842, 43985.56014439814, 43039.784801075795, 43149.0, 43491.13017739691, 43624.0, 43996.0, 42496.15007699891, 43026.569734302255, 43592.56407435885, 43678.12643747044, 43816.0, 35193.0, 37156.25687486289, 37520.0, 37945.0, 38641.22717545577, 27247.31881702894, 27348.863255683595, 27994.16017519478, 28417.0, 28467.145985619634], "value": 19658.576228358488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.6603223131412, 382.79096496439945, 388.87944737131494, 399.1060025542785, 403.39289369498897, 14737.48418805342, 18333.266669333243, 19058.809411905895, 20339.389818305455, 20378.184872605096, 14434.566962991126, 18513.259469621233, 20107.0, 20176.59646807063, 20192.29326973556, 5638.774449022034, 5768.278965129359, 5800.245968024151, 5829.417058294179, 5841.795537156179, 4257.467816522934, 5727.713614319288, 5786.392428794979, 6086.634801911878, 6393.584417012879, 6409.070684750708, 6413.583117097397, 6455.419012288893, 6471.4175724184815, 6474.870502589969, 6250.937490625081, 6404.1674582304, 6411.3588641135975, 6412.230532336102, 6412.935870641304, 5465.015807690573, 5597.0, 5654.038813401731, 5667.943320566824, 5668.68822214778, 24374.512509749704, 24798.132065377806, 24813.50372992548, 24878.0, 25244.242672719847, 2185.2570126157184, 2437.512497500489, 2505.5991041433285, 2964.451576458362, 3105.819788480386, 14422.06256593318, 14888.851111488962, 15010.099394036393, 15442.536723898302, 15659.530214093595, 1910.6083252933115, 1934.332655233942, 1978.5548251643409, 2026.6858636911302, 2064.2878207018553, 85623.5718821402, 87396.0, 88539.11460885438, 89778.0, 90288.645670315, 11038.964284821577, 11126.332420054823, 11292.604758833402, 11351.659450216506, 11555.480003399867, 15245.23773811305, 15317.0, 15368.692626147415, 15381.846181538041, 15424.46014389491, 8208.343332533503, 8473.533955632376, 8480.363972701949, 8521.36089793268, 8546.273566746771, 7197.856042879113, 8227.8354432911, 8242.17578242171, 8341.20758527939, 8380.497170169809, 4525.818967241339, 4733.124371991193, 4848.248521479177, 4880.902381952341, 4884.535969082932, 1914.8468122550162, 1934.9559420810574, 1937.2347922570507, 1958.4222654317011, 1985.2852972929734, 861.646724842824, 894.592960203106, 929.7489677786959, 952.3619175152664, 982.8280050991048, 18727.563515367012, 20009.199632014846, 20201.090950907244, 20262.088206030763, 20366.592668146553, 952.3524003677566, 977.5503268496429, 981.3375971219398, 987.4963768478128, 1012.367270455965, 10952.835707464394, 11159.19920894814, 11195.384253866116, 11270.21108522412, 11364.318140911571, 183080.0, 480083.0, 504613.0, 529302.0, 531654.0, 89834.55082724545, 91106.0, 92123.0, 92900.0, 95388.52305738427, 1008.5209525475341, 1011.8886922438526, 1013.7414959185508, 1014.9289549731448, 1023.2121266624657, 17920.193591288164, 17943.102844857967, 18333.908330458264, 18445.16996735177, 18483.815161848746, 43810.34284485799, 44557.0, 44752.55247447484, 45400.0, 45888.0, 58858.41141588529, 60397.69801150966, 60656.6967165179, 61979.38020619736, 62273.68863155836, 8734.0, 8858.955705221433, 8878.778030549029, 8897.28821694276, 9092.727218183201, 10167.430735499067, 14026.158430494203, 14061.437542498186, 14332.641683957985, 14511.1293322401, 11911.940440297743, 12047.698807529881, 12401.565945191875, 12464.937675311565, 12561.309127998047, 11409.543618255177, 11941.402929853299, 12005.699857503632, 12185.147039707135, 12486.37568121572, 12508.93745531303, 12580.182288151242, 12638.178518396275, 12820.0, 12917.870821291666, 8774.649014039622, 8808.86786698213, 8848.0, 8867.113288671082, 9119.0, 9270.860937086083, 12218.389080546114, 12408.937955310166, 12549.623511294676, 12645.93677031609], "value": 9551.980446847932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.0776548650458, 1451.3178805961202, 1504.0975414751151, 1576.8028996375454, 1771.38887083956, 8462.619182136803, 8927.241184499317, 8972.95513522433, 9188.954055229715, 9194.816103677938, 8888.866666999984, 9063.093690630934, 9069.773255668595, 9078.77303067422, 9080.182783549479, 2335.789778919897, 2353.470469144437, 2354.9175778847743, 2357.3752955466816, 2367.242482405631, 2334.159702507096, 2382.4758553118286, 2386.343755467252, 2387.8447900886385, 2390.916317928873, 2301.2405906050963, 2305.8385912986096, 2334.719833619968, 2338.8596684199, 2341.555104530142, 2038.7349644546114, 2072.181488312116, 2074.2532688232222, 2083.645780217359, 2084.5622419291967, 2237.2393386248755, 2239.6080685879906, 2246.809021233197, 2247.112390605718, 2247.1685476373846, 8779.736607901774, 8980.326475514363, 9002.684906028324, 9030.638774449015, 9144.359894807367, 5228.633995620308, 5306.09796334624, 5325.920111198335, 5361.195820626898, 5364.865878353034, 3462.6191118976894, 3464.566929133858, 3502.474628805674, 3515.1915059536195, 3520.700740437066, 1177.2583272538332, 1181.533294348736, 1190.2798806721983, 1192.749522600255, 1200.2438463767858, 11880.108991825648, 11965.401729913556, 12029.759404811943, 12037.518499259932, 12061.216020958609, 4240.597143271384, 4297.613214810682, 4401.867943961686, 4408.625266852289, 4475.060237350193, 9707.563159657833, 9710.320277580642, 9711.757206069904, 9718.902810971942, 9788.9021109788, 5107.361579802525, 5114.846554603368, 5125.846224613268, 5144.331236939174, 5147.948520514822, 4202.537720850704, 4631.212693842055, 4800.975995120002, 4849.4908034655855, 4872.4153101628035, 3819.6371344722206, 3845.2309538092495, 3889.78606176663, 3894.8636797711943, 3951.5258169019326, 1126.743680795923, 1143.1826244235244, 1149.184079303687, 1155.1971379891063, 1161.1059484197121, 774.3379410603852, 967.6806653804227, 1003.9799204016025, 1011.9038691324313, 1012.6253286283975, 5982.760689572456, 6157.322694503601, 6275.843103922439, 6307.337729538424, 6464.030395440627, 458.7041358323929, 460.1073916601849, 484.94180698316427, 493.2502596053978, 657.7699701558022, 4135.276326642826, 4401.559844015573, 4404.273294906368, 4410.580994805488, 4413.227685155085, 10608.522616482276, 11014.944925275482, 11035.117190624731, 11108.0, 11118.833217501744, 10618.203634727426, 10950.835737463944, 10955.616553420752, 10965.61620343284, 11003.559857605605, 8242.752717418487, 8445.873311900326, 8557.0, 8609.22516973463, 8617.655293788304, 6253.843653908689, 6411.8717625646295, 6467.644279564669, 6517.9348206517325, 6566.113574667359, 10404.739881503023, 10561.313514621525, 10582.894171058499, 10645.94677026641, 10658.946705266424, 10087.546060427152, 10472.371657700562, 10476.476176191007, 10701.0, 10795.352278863293, 7376.225496322809, 8338.583070846555, 8493.532855692996, 8512.40413171072, 8676.609552570275, 8882.378233523592, 8892.244159246537, 8956.328275379503, 8959.73120806377, 8968.05835387275, 7539.132999705104, 7560.773176804704, 7780.727674531364, 7951.960240198762, 8099.8380032397845, 7770.689172433039, 7827.373810095065, 7979.84040319202, 8142.154498028279, 8145.266925976691, 7098.396636285974, 7142.964285178541, 7197.928020719726, 7202.099737532809, 7202.135743710788, 5442.482964118402, 5483.862903427446, 5511.669299842022, 5566.638168519034, 5611.410801865745, 5127.307813445137, 5175.378954525481, 5252.7898884045735, 5276.445973172762, 5493.09363954943], "value": 4602.897303518222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.47610680430694, 328.8996855958933, 333.8814720774125, 334.56673607678056, 339.46195280480447, 5161.793528258865, 5290.470952904708, 5371.301730775003, 5391.460853914607, 5430.321209848769, 5290.153575427932, 5305.946940530604, 5309.522143007129, 5316.867078323044, 5330.253764472977, 4194.538600753915, 4194.664426845845, 4199.643030342425, 4212.136512015029, 4223.767692776896, 3972.768441783042, 4040.313146765057, 4193.391958166064, 4213.5997080277475, 4231.301835197189, 4190.476190476191, 4193.245215861144, 4203.180379825935, 4209.579042095781, 4237.131388065454, 4175.91648167035, 4179.644730197937, 4185.414042034103, 4190.685698572619, 4212.957870421318, 3604.441311596707, 3710.128119891823, 3765.5293088363956, 3775.3959366501504, 3777.9055523611855, 5079.314292570492, 5094.1849304111165, 5113.054084994288, 5171.7672704728375, 5173.974130129325, 4689.109069276827, 4709.929351059737, 4721.976390118062, 4731.793392684844, 4757.1437141314545, 5321.840344789663, 5482.5888058395385, 5522.806701765419, 5523.547782635853, 5541.33503979521, 3984.183242435294, 4033.475648165749, 4034.688726163979, 4041.6766658667234, 4063.553009168989, 5756.194132821408, 5761.9135712964335, 5765.3369862465515, 5781.248437703071, 5786.276715410616, 4008.579099194601, 4186.434831297735, 4188.32986722123, 4191.7484950903045, 4199.790010499462, 4162.646175075092, 4198.223328684203, 4260.595243451867, 4262.573742625713, 4277.529471758104, 4714.727023703581, 4744.691595046311, 4757.405324334458, 4767.856964291077, 4769.475357710649, 5433.6196466247775, 5529.30883639545, 5547.306586676666, 5588.636738611977, 5602.13166959122, 1436.9281535923162, 1446.7323545144181, 1454.5709015840353, 1455.3087283540444, 1461.5688371930305, 930.9759264808791, 943.9858402123974, 966.0291407135924, 970.9125779127422, 973.493783232715, 4713.421003963642, 4718.457377401574, 4751.121042607128, 4761.286196766528, 4779.30700048498, 4949.282354058641, 4956.3804524434445, 4983.277424773388, 5001.699898006132, 5015.319867844313, 4494.505604383515, 4571.3600095986585, 4695.812167513262, 4708.105459962596, 4746.288056791509, 2663.4673065387, 2672.7438104091434, 2676.839389636628, 2679.9364489343097, 2686.3821321096257, 5127.769250383742, 5152.690838549699, 5163.586913047023, 5170.379554453414, 5180.171172612364, 5113.565346945551, 5128.102582048201, 5168.191133103459, 5406.216098665672, 5829.038208695518, 5820.708964551755, 5855.9121613176685, 5881.5882888198275, 5891.145783861316, 5904.1734157218025, 5686.346070201815, 5749.626274292157, 5759.539236861125, 5767.596268261182, 5772.5381969441505, 5523.420040895809, 5612.540739407702, 5619.775208991595, 5649.971750141223, 5681.460261275172, 5886.970565147147, 5907.409259074058, 5913.2608423947, 5915.93513167634, 5955.880882382415, 3911.882643520699, 4097.221527909687, 4106.691998100095, 4157.376393540993, 4193.937090943699, 5636.718164091697, 5639.407862174413, 5652.576056795675, 5680.545556355399, 5704.971475142598, 5522.917156242579, 5547.944520554743, 5600.215969764155, 5611.915821262603, 5621.971890140523, 5309.938012397459, 5605.383407825218, 5609.429359779268, 5616.241807355955, 5617.129344951539, 5584.6492104736835, 5593.133064375028, 5594.86012849682, 5600.7199640017025, 5675.829725108253, 5273.103572392626, 5566.972165139148, 5616.798424897769, 5715.371309156073, 5726.541876649775, 5420.214068960057, 5451.509364157245, 5459.7270136492225, 5469.535089517435, 5486.670799752028], "value": 4182.844245636083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.6266866566716, 802.3621221129201, 806.0931452116373, 833.654033576066, 860.9827803443933, 14921.626959326022, 15491.225438728075, 15493.0, 15529.0, 15542.53372398827, 17330.48008559742, 17341.306347746107, 17461.650766984654, 17539.82460175398, 17559.91220043898, 669.3741351836024, 799.2367289238763, 812.4556547113418, 814.9633266502992, 817.979550511239, 827.2472050434106, 827.2472050434106, 827.3711978896052, 828.031203491913, 832.9795999900105, 859.3941271403677, 862.3144600042981, 865.6364326982651, 866.2680035370103, 866.3762091294258, 694.7429451103073, 831.2186544648011, 857.6826574167535, 859.2009431228954, 860.5009094725044, 608.3429895712663, 613.2273335597135, 613.6164896939413, 613.9355367686418, 613.9938600613981, 15702.214889255605, 15715.214239288105, 15715.528534143994, 15723.056616602926, 15734.449294274646, 13887.236202008886, 14172.787408188886, 14214.715705685829, 14651.487197948127, 14822.851771482256, 13634.250116243602, 13788.172709637354, 13906.652333691638, 13944.860551394559, 14000.369983350774, 4543.727376357429, 4578.565036321577, 4651.186042442559, 4729.881752956169, 4732.763361831895, 60091.39908600902, 60776.69611651913, 82823.58588207081, 84277.15722842755, 86100.56949715274, 11413.714657133472, 11596.884031159749, 11650.6504804856, 11689.123315751352, 11790.410479475991, 21473.355799326044, 21807.45481362947, 21809.127634894743, 21850.0, 21851.125954961943, 8452.492850428995, 8473.195046470575, 8654.35092368075, 8759.868601970975, 8796.560171991376, 4643.141018911512, 4709.882252943636, 4716.882077948078, 4766.547178018082, 4780.043991201704, 3399.184195792992, 3510.350585141757, 3511.367953768346, 3516.4725291206523, 3525.25969546393, 615.7013848283578, 619.0961196652937, 625.9624422534663, 631.1353445779312, 631.2488139114439, 578.6817250512202, 625.1810128731377, 676.7225437570572, 681.465049935798, 688.18793823288, 9690.273229507815, 9706.854397184048, 10145.644902428527, 10374.896251037544, 10485.633002845016, 621.5276389943675, 650.4536189600664, 684.5276759036249, 687.71116131225, 692.8995295682098, 4454.837065683401, 4668.929966050512, 4685.742284174335, 4785.617150627942, 4861.708297502161, 117461.0, 123217.38391307986, 124449.37775311247, 126132.0, 127319.36340318239, 64554.0, 108947.45526272318, 109204.0, 109257.45371273093, 111637.0, 62690.99999999909, 92799.0, 95138.0486195143, 95558.0, 98479.0, 9732.854007189757, 10079.848802267825, 10217.489125543842, 10263.846042309222, 10520.473976301308, 44653.77673111614, 50054.49945500498, 53377.73311133419, 54196.0, 56659.716701416226, 82689.0, 87073.56463217644, 89946.0, 90024.0, 91003.54498227731, 11230.382328971986, 13965.720685586435, 14002.929985350009, 14093.788593170906, 14349.784753228501, 15555.611109722347, 15932.0, 16347.182640867672, 16747.581310467336, 16882.493525194262, 10859.857997858408, 13962.441502339792, 15036.774448383501, 15619.84380156184, 15630.765538516704, 14980.625484362978, 15184.46854360092, 15823.0, 16313.26590303463, 16331.59171020734, 14881.553553393416, 14889.255537223313, 14900.561295127418, 15353.769693454384, 16177.4337898173, 11574.6527604172, 11594.942025290156, 11644.592439264585, 11665.59170429031, 11950.880491195325, 12103.455344509694, 12249.510019599116, 12489.687757805765, 12649.177803442746, 12721.236725796482], "value": 7863.135321799989, "unit": "score"}], "name": "dromaeo_css", "value": 7300.566371108059, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 111.0, 110.0, 112.0, 111.0, 113.0, 111.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 120.0, 172.0, 117.0, 119.0, 148.0, 118.0, 167.0, 116.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 162.0, 161.0, 161.0, 162.0, 162.0, 163.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 125.0, 75.0, 76.0, 75.0, 75.0, 105.0, 73.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 119.0, 84.0, 85.0, 85.0, 114.0, 83.0, 84.0, 121.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 59.0, 55.0, 55.0, 55.0, 56.0, 56.0, 56.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 63.0, 63.0, 65.0, 64.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 75.0, 75.0, 76.0, 74.0, 75.0, 74.0, 78.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 127.0, 119.0, 122.0, 124.0, 120.0, 142.0, 167.0, 118.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 161.0, 164.0, 159.0, 164.0, 170.0, 161.0, 161.0, 161.0, 167.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 62.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1493.3000000000002, "alertThreshold": 2.0}]} 16:32:53 INFO - Return code: 0 16:32:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:32:53 INFO - # TBPL SUCCESS # 16:32:53 INFO - Running post-action listener: _resource_record_post_action 16:32:53 INFO - ##### 16:32:53 INFO - ##### Finished run-tests step (success) 16:32:53 INFO - ##### 16:32:53 INFO - Running post-run listener: _resource_record_post_run 16:32:53 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 563867648; Read time: 264; Write time: 795196 16:32:53 INFO - TinderboxPrint: CPU usage
13.0% 16:32:53 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 264 16:32:53 INFO - TinderboxPrint: I/O write bytes / time
563,867,648 / 795,196 16:32:53 INFO - TinderboxPrint: CPU idle
8,276.0 (87.0%) 16:32:53 INFO - TinderboxPrint: CPU user
1,190.4 (12.5%) 16:32:53 INFO - TinderboxPrint: Swap in / out
0 / 0 16:32:53 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 269897728; Read time: 0; Write time: 473584 16:32:53 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293969920; Read time: 244; Write time: 321612 16:32:54 INFO - Running post-run listener: _upload_blobber_files 16:32:54 INFO - Blob upload gear active. 16:32:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:32:54 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 16:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:32:54 INFO - (blobuploader) - INFO - Open directory for files ... 16:32:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:59 INFO - (blobuploader) - INFO - Done attempting. 16:32:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:32:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:33:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:00 INFO - (blobuploader) - INFO - Done attempting. 16:33:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:33:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:33:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:00 INFO - (blobuploader) - INFO - Done attempting. 16:33:00 INFO - (blobuploader) - INFO - Iteration through files over. 16:33:00 INFO - Return code: 0 16:33:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:33:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:33:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8619ef0ee4a6ec6013a2653b369644cc4f65386d2a42adfa770ce91d9b34a78f412be4a862c7fab6666013cb3dc62a3c28e8244d5bfcc87950fab6a90149cb20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b61a67db08544b84f77f79cc7c5c67b25d4f8ab7285d6fc128c1744b7d5b95892d8fa7f3391f95248f288b5757571a81ee69907c5812d8908028ed7c615946f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7863fbcefcff1405471b2275ed6de903f7b806ffcd6024b12274c7cccd6ed2bed0d782b73d8b05506d7c436a6f2e55e451ad0cbc96e2ded44de26d2223cac682"} 16:33:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:33:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:33:00 INFO - Contents: 16:33:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8619ef0ee4a6ec6013a2653b369644cc4f65386d2a42adfa770ce91d9b34a78f412be4a862c7fab6666013cb3dc62a3c28e8244d5bfcc87950fab6a90149cb20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b61a67db08544b84f77f79cc7c5c67b25d4f8ab7285d6fc128c1744b7d5b95892d8fa7f3391f95248f288b5757571a81ee69907c5812d8908028ed7c615946f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7863fbcefcff1405471b2275ed6de903f7b806ffcd6024b12274c7cccd6ed2bed0d782b73d8b05506d7c436a6f2e55e451ad0cbc96e2ded44de26d2223cac682"} 16:33:00 INFO - Running post-run listener: copy_logs_to_upload_dir 16:33:00 INFO - Copying logs to upload dir... 16:33:00 INFO - mkdir: /builds/slave/test/build/upload/logs 16:33:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.463576 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-07-11 16:33:00.711266) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 16:33:00.714982) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8619ef0ee4a6ec6013a2653b369644cc4f65386d2a42adfa770ce91d9b34a78f412be4a862c7fab6666013cb3dc62a3c28e8244d5bfcc87950fab6a90149cb20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b61a67db08544b84f77f79cc7c5c67b25d4f8ab7285d6fc128c1744b7d5b95892d8fa7f3391f95248f288b5757571a81ee69907c5812d8908028ed7c615946f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7863fbcefcff1405471b2275ed6de903f7b806ffcd6024b12274c7cccd6ed2bed0d782b73d8b05506d7c436a6f2e55e451ad0cbc96e2ded44de26d2223cac682"} build_url:https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/fJr9ruZITBWsryQTabOb9A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8619ef0ee4a6ec6013a2653b369644cc4f65386d2a42adfa770ce91d9b34a78f412be4a862c7fab6666013cb3dc62a3c28e8244d5bfcc87950fab6a90149cb20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b61a67db08544b84f77f79cc7c5c67b25d4f8ab7285d6fc128c1744b7d5b95892d8fa7f3391f95248f288b5757571a81ee69907c5812d8908028ed7c615946f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7863fbcefcff1405471b2275ed6de903f7b806ffcd6024b12274c7cccd6ed2bed0d782b73d8b05506d7c436a6f2e55e451ad0cbc96e2ded44de26d2223cac682"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 16:33:00.744082) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:33:00.744485) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1468278691.405224-1152978138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:33:00.827693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 16:33:00.828262) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 16:33:00.828918) ========= ========= Total master_lag: 0.60 =========