builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-076 starttime: 1486689568.89 results: success (0) revision: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:28.892170) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:28.892701) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:28.892990) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:28.910367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:28.910845) ========= 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=9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 17:19:29-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2017-02-09 17:19:29 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109453 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.034569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.034996) ========= 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=9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015824 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.084910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.085285) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63752d3df9fa09ecd3283eaccb950cc5607fbab1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63752d3df9fa09ecd3283eaccb950cc5607fbab1 --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=9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 17:19:29,233 truncating revision to first 12 chars 2017-02-09 17:19:29,233 Setting DEBUG logging. 2017-02-09 17:19:29,233 attempt 1/10 2017-02-09 17:19:29,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/63752d3df9fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 17:19:29,666 unpacking tar archive at: mozilla-aurora-63752d3df9fa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.735062 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.834234) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.834753) ========= script_repo_revision: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.835208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.835509) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 17:19:29.845958) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 17:19:29.846472) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:19:30 INFO - MultiFileLogger online at 20170209 17:19:30 in /builds/slave/test 17:19:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:19:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:19:30 INFO - {'append_to_log': False, 17:19:30 INFO - 'base_work_dir': '/builds/slave/test', 17:19:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:19:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:19:30 INFO - 'branch': 'Mozilla-Aurora', 17:19:30 INFO - 'buildbot_json_path': 'buildprops.json', 17:19:30 INFO - 'config_files': ('talos/linux_config.py',), 17:19:30 INFO - 'default_actions': ('clobber', 17:19:30 INFO - 'read-buildbot-config', 17:19:30 INFO - 'download-and-extract', 17:19:30 INFO - 'populate-webroot', 17:19:30 INFO - 'create-virtualenv', 17:19:30 INFO - 'install', 17:19:30 INFO - 'run-tests'), 17:19:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:19:30 INFO - 'download_minidump_stackwalk': True, 17:19:30 INFO - 'download_symbols': 'ondemand', 17:19:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:19:30 INFO - 'tooltool.py': '/tools/tooltool.py', 17:19:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:19:30 INFO - '/tools/misc-python/virtualenv.py')}, 17:19:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:19:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:19:30 INFO - 'installer_path': 'installer.exe', 17:19:30 INFO - 'log_level': 'info', 17:19:30 INFO - 'log_name': 'talos', 17:19:30 INFO - 'log_to_console': True, 17:19:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:19:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:19:30 INFO - 'opt_config_files': (), 17:19:30 INFO - 'pip_index': False, 17:19:30 INFO - 'sps_profile': False, 17:19:30 INFO - 'sps_profile_interval': 0, 17:19:30 INFO - 'suite': 'dromaeojs', 17:19:30 INFO - 'system_bits': '32', 17:19:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:19:30 INFO - 'title': 'talos-linux64-ix-076', 17:19:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:19:30 INFO - 'use_talos_json': True, 17:19:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:19:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:19:30 INFO - 'work_dir': 'build'} 17:19:30 INFO - [mozharness: 2017-02-10 01:19:30.094282Z] Running clobber step. 17:19:30 INFO - Running pre-action listener: _resource_record_pre_action 17:19:30 INFO - Running main action method: clobber 17:19:30 INFO - rmtree: /builds/slave/test/build 17:19:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:19:30 INFO - Running post-action listener: _resource_record_post_action 17:19:30 INFO - [mozharness: 2017-02-10 01:19:30.556971Z] Finished clobber step (success) 17:19:30 INFO - [mozharness: 2017-02-10 01:19:30.557070Z] Running read-buildbot-config step. 17:19:30 INFO - Running pre-action listener: _resource_record_pre_action 17:19:30 INFO - Running main action method: read_buildbot_config 17:19:30 INFO - Using buildbot properties: 17:19:30 INFO - { 17:19:30 INFO - "project": "", 17:19:30 INFO - "product": "firefox", 17:19:30 INFO - "who": "ryanvm@gmail.com", 17:19:30 INFO - "installer_path": "public/build/target.tar.bz2", 17:19:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:19:30 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 17:19:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:19:30 INFO - "stage_platform": "linux64-pgo", 17:19:30 INFO - "basedir": "/builds/slave/test", 17:19:30 INFO - "buildnumber": 1048, 17:19:30 INFO - "platform": "ubuntu64_hw", 17:19:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:19:30 INFO - "slavebuilddir": "test", 17:19:30 INFO - "taskId": "ZfxxjvCuQUqe1qnyA-xVEw", 17:19:30 INFO - "branch": "mozilla-aurora", 17:19:30 INFO - "script_repo_revision": "production", 17:19:30 INFO - "revision": "63752d3df9fa09ecd3283eaccb950cc5607fbab1", 17:19:30 INFO - "slavename": "talos-linux64-ix-076", 17:19:30 INFO - "repo_path": "releases/mozilla-aurora" 17:19:30 INFO - } 17:19:30 INFO - Finding installer, test and symbols from parent task. 17:19:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZfxxjvCuQUqe1qnyA-xVEw'}, attempt #1 17:19:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZfxxjvCuQUqe1qnyA-xVEw'}, attempt #1 17:19:31 INFO - Task dependencies: K2mQcSWIRz6Kq_6qBuDvyw 17:19:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw'}, attempt #1 17:19:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2 17:19:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/test_packages.json 17:19:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.crashreporter-symbols.zip 17:19:31 INFO - Running post-action listener: _resource_record_post_action 17:19:31 INFO - [mozharness: 2017-02-10 01:19:31.981421Z] Finished read-buildbot-config step (success) 17:19:31 INFO - [mozharness: 2017-02-10 01:19:31.981637Z] Running download-and-extract step. 17:19:31 INFO - Running pre-action listener: _resource_record_pre_action 17:19:31 INFO - Running main action method: download_and_extract 17:19:31 INFO - mkdir: /builds/slave/test/build/tests 17:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:31 INFO - trying https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.test_packages.json 17:19:31 INFO - Downloading https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:19:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:19:32 INFO - Downloaded 896 bytes. 17:19:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:19:32 INFO - Using the following test package requirements: 17:19:32 INFO - {u'common': [u'target.common.tests.zip'], 17:19:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:19:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:19:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:19:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:19:32 INFO - u'mozbase': [u'target.common.tests.zip'], 17:19:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:19:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:19:32 INFO - u'web-platform': [u'target.common.tests.zip', 17:19:32 INFO - u'target.web-platform.tests.zip'], 17:19:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:19:32 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:19:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.common.tests.zip 17:19:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:19:32 INFO - Fetch https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.common.tests.zip into memory 17:19:33 INFO - Expected file size: 21855631 17:19:33 INFO - Obtained file size: 21855631 17:19:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:19:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.common.tests.zip 17:19:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:19:37 INFO - Fetch https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.common.tests.zip into memory 17:19:38 INFO - Expected file size: 21855631 17:19:38 INFO - Obtained file size: 21855631 17:19:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.talos.tests.zip 17:19:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:19:42 INFO - Fetch https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.talos.tests.zip into memory 17:19:43 INFO - Expected file size: 14403485 17:19:43 INFO - Obtained file size: 14403485 17:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:43 INFO - trying https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2 17:19:43 INFO - Downloading https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:19:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:19:44 INFO - Downloaded 66106545 bytes. 17:19:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2 17:19:44 INFO - mkdir: /builds/slave/test/properties 17:19:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:19:44 INFO - Writing to file /builds/slave/test/properties/build_url 17:19:44 INFO - Contents: 17:19:44 INFO - build_url:https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2 17:19:44 INFO - Running post-action listener: _resource_record_post_action 17:19:44 INFO - Running post-action listener: set_extra_try_arguments 17:19:44 INFO - [mozharness: 2017-02-10 01:19:44.816760Z] Finished download-and-extract step (success) 17:19:44 INFO - [mozharness: 2017-02-10 01:19:44.816855Z] Running populate-webroot step. 17:19:44 INFO - Running pre-action listener: _resource_record_pre_action 17:19:44 INFO - Running main action method: populate_webroot 17:19:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:19:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:19:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:19:44 INFO - '--tppagecycles', 17:19:44 INFO - '7'], 17:19:44 INFO - 'tests': ['tsvgm']}}, 17:19:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:19:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:19:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:19:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:19:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['damp', 'tps']}, 17:19:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'tests': ['damp', 'tps']}, 17:19:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['dromaeo_dom']}, 17:19:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:19:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['basic_compositor_video']}, 17:19:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:19:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['a11yr', 17:19:44 INFO - 'ts_paint', 17:19:44 INFO - 'tpaint', 17:19:44 INFO - 'sessionrestore', 17:19:44 INFO - 'sessionrestore_no_auto_restore', 17:19:44 INFO - 'tabpaint']}, 17:19:44 INFO - 'other-e10s': {'tests': ['a11yr', 17:19:44 INFO - 'ts_paint', 17:19:44 INFO - 'tpaint', 17:19:44 INFO - 'sessionrestore', 17:19:44 INFO - 'sessionrestore_no_auto_restore', 17:19:44 INFO - 'tabpaint']}, 17:19:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:19:44 INFO - 'ts_paint', 17:19:44 INFO - 'tpaint', 17:19:44 INFO - 'sessionrestore', 17:19:44 INFO - 'sessionrestore_no_auto_restore', 17:19:44 INFO - 'tabpaint']}, 17:19:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:19:44 INFO - 'ts_paint', 17:19:44 INFO - 'tpaint', 17:19:44 INFO - 'sessionrestore', 17:19:44 INFO - 'sessionrestore_no_auto_restore', 17:19:44 INFO - 'tabpaint']}, 17:19:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['a11yr', 17:19:44 INFO - 'ts_paint', 17:19:44 INFO - 'tpaint', 17:19:44 INFO - 'sessionrestore', 17:19:44 INFO - 'sessionrestore_no_auto_restore', 17:19:44 INFO - 'tabpaint']}, 17:19:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['a11yr', 17:19:44 INFO - 'ts_paint', 17:19:44 INFO - 'tpaint', 17:19:44 INFO - 'sessionrestore', 17:19:44 INFO - 'sessionrestore_no_auto_restore', 17:19:44 INFO - 'tabpaint']}, 17:19:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['tsvgx', 17:19:44 INFO - 'tsvgr_opacity', 17:19:44 INFO - 'tart', 17:19:44 INFO - 'tscrollx', 17:19:44 INFO - 'cart', 17:19:44 INFO - 'tsvg_static']}, 17:19:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:19:44 INFO - 'tsvgr_opacity', 17:19:44 INFO - 'tart', 17:19:44 INFO - 'tscrollx', 17:19:44 INFO - 'cart', 17:19:44 INFO - 'tsvg_static']}, 17:19:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'talos_options': ['--disable-e10s'], 17:19:44 INFO - 'tests': ['tp5o']}, 17:19:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:19:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'talos_options': ['--disable-e10s', 17:19:44 INFO - '--xperf_path', 17:19:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:44 INFO - 'tests': ['tp5n']}, 17:19:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:19:44 INFO - 'talos_options': ['--xperf_path', 17:19:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:44 INFO - 'tests': ['tp5n']}}, 17:19:44 INFO - 'talos.zip': {'path': '', 17:19:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:19:44 INFO - Running post-action listener: _resource_record_post_action 17:19:44 INFO - [mozharness: 2017-02-10 01:19:44.829832Z] Finished populate-webroot step (success) 17:19:44 INFO - [mozharness: 2017-02-10 01:19:44.829921Z] Running create-virtualenv step. 17:19:44 INFO - Running pre-action listener: _resource_record_pre_action 17:19:44 INFO - Running main action method: create_virtualenv 17:19:44 INFO - Creating virtualenv /builds/slave/test/build/venv 17:19:44 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 17:19:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:19:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:19:44 INFO - Using real prefix '/usr' 17:19:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:19:45 INFO - Installing distribute.............................................................................................................................................................................................done. 17:19:47 INFO - Installing pip.................done. 17:19:47 INFO - Return code: 0 17:19:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:19:47 INFO - Reading from file tmpfile_stdout 17:19:47 INFO - Output received: 17:19:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:19:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19: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', 'pip>=1.5'] in /builds/slave/test/build 17:19: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 pip>=1.5 17:19:47 INFO - Using env: {'DISPLAY': ':0', 17:19:47 INFO - 'HOME': '/home/cltbld', 17:19:47 INFO - 'LANG': 'en_US.UTF-8', 17:19:47 INFO - 'LANGUAGE': 'en_US:en', 17:19:47 INFO - 'LOGNAME': 'cltbld', 17:19:47 INFO - 'MAIL': '/var/mail/cltbld', 17:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:47 INFO - 'MOZ_NO_REMOTE': '1', 17:19:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:47 INFO - 'NO_EM_RESTART': '1', 17:19:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:47 INFO - 'PWD': '/builds/slave/test', 17:19:47 INFO - 'SHELL': '/bin/bash', 17:19:47 INFO - 'SHLVL': '1', 17:19:47 INFO - 'TERM': 'linux', 17:19:47 INFO - 'TMOUT': '86400', 17:19:47 INFO - 'USER': 'cltbld', 17:19:47 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:47 INFO - '_': '/tools/buildbot/bin/python'} 17:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:47 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 17:19:47 INFO - Cleaning up... 17:19:47 INFO - Return code: 0 17:19:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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', '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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:19: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 psutil>=3.1.1 17:19:47 INFO - Using env: {'DISPLAY': ':0', 17:19:47 INFO - 'HOME': '/home/cltbld', 17:19:47 INFO - 'LANG': 'en_US.UTF-8', 17:19:47 INFO - 'LANGUAGE': 'en_US:en', 17:19:47 INFO - 'LOGNAME': 'cltbld', 17:19:47 INFO - 'MAIL': '/var/mail/cltbld', 17:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:47 INFO - 'MOZ_NO_REMOTE': '1', 17:19:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:47 INFO - 'NO_EM_RESTART': '1', 17:19:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:47 INFO - 'PWD': '/builds/slave/test', 17:19:47 INFO - 'SHELL': '/bin/bash', 17:19:47 INFO - 'SHLVL': '1', 17:19:47 INFO - 'TERM': 'linux', 17:19:47 INFO - 'TMOUT': '86400', 17:19:47 INFO - 'USER': 'cltbld', 17:19:47 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:47 INFO - '_': '/tools/buildbot/bin/python'} 17:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:48 INFO - Downloading/unpacking psutil>=3.1.1 17:19:48 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 17:19:48 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 17:19:48 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 17:19:48 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 17:19:48 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 17:19:48 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 17:19:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:19:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:50 INFO - Installing collected packages: psutil 17:19:50 INFO - Running setup.py install for psutil 17:19:50 INFO - building 'psutil._psutil_linux' extension 17:19:50 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 17:19:50 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 17:19:50 INFO - building 'psutil._psutil_posix' extension 17:19:50 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 17:19:50 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 17:19:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:50 INFO - Successfully installed psutil 17:19:50 INFO - Cleaning up... 17:19:50 INFO - Return code: 0 17:19:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19:50 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 17:19:50 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 17:19:50 INFO - Using env: {'DISPLAY': ':0', 17:19:50 INFO - 'HOME': '/home/cltbld', 17:19:50 INFO - 'LANG': 'en_US.UTF-8', 17:19:50 INFO - 'LANGUAGE': 'en_US:en', 17:19:50 INFO - 'LOGNAME': 'cltbld', 17:19:50 INFO - 'MAIL': '/var/mail/cltbld', 17:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:50 INFO - 'MOZ_NO_REMOTE': '1', 17:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:50 INFO - 'NO_EM_RESTART': '1', 17:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:50 INFO - 'PWD': '/builds/slave/test', 17:19:50 INFO - 'SHELL': '/bin/bash', 17:19:50 INFO - 'SHLVL': '1', 17:19:50 INFO - 'TERM': 'linux', 17:19:50 INFO - 'TMOUT': '86400', 17:19:50 INFO - 'USER': 'cltbld', 17:19:50 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:50 INFO - '_': '/tools/buildbot/bin/python'} 17:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:19:52 INFO - Installing collected packages: mozsystemmonitor 17:19:52 INFO - Running setup.py install for mozsystemmonitor 17:19:53 INFO - Successfully installed mozsystemmonitor 17:19:53 INFO - Cleaning up... 17:19:53 INFO - Return code: 0 17:19:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:53 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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19:53 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 17:19:53 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 17:19:53 INFO - Using env: {'DISPLAY': ':0', 17:19:53 INFO - 'HOME': '/home/cltbld', 17:19:53 INFO - 'LANG': 'en_US.UTF-8', 17:19:53 INFO - 'LANGUAGE': 'en_US:en', 17:19:53 INFO - 'LOGNAME': 'cltbld', 17:19:53 INFO - 'MAIL': '/var/mail/cltbld', 17:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:53 INFO - 'MOZ_NO_REMOTE': '1', 17:19:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:53 INFO - 'NO_EM_RESTART': '1', 17:19:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:53 INFO - 'PWD': '/builds/slave/test', 17:19:53 INFO - 'SHELL': '/bin/bash', 17:19:53 INFO - 'SHLVL': '1', 17:19:53 INFO - 'TERM': 'linux', 17:19:53 INFO - 'TMOUT': '86400', 17:19:53 INFO - 'USER': 'cltbld', 17:19:53 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:53 INFO - '_': '/tools/buildbot/bin/python'} 17:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:53 INFO - Downloading/unpacking jsonschema==2.5.1 17:19:53 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 17:19:53 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 17:19:53 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 17:19:53 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 17:19:53 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 17:19:53 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 17:19:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:19:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:19:55 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 17:19:55 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 17:19:55 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 17:19:55 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 17:19:55 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 17:19:55 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 17:19:55 INFO - Downloading functools32-3.2.3-2.tar.gz 17:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:19:55 INFO - warning: no files found matching '*.txt' 17:19:55 INFO - no previously-included directories found matching 'build' 17:19:55 INFO - no previously-included directories found matching 'dist' 17:19:55 INFO - no previously-included directories found matching '.git*' 17:19:55 INFO - Installing collected packages: jsonschema, functools32 17:19:55 INFO - Running setup.py install for functools32 17:19:55 INFO - warning: no files found matching '*.txt' 17:19:55 INFO - no previously-included directories found matching 'build' 17:19:55 INFO - no previously-included directories found matching 'dist' 17:19:55 INFO - no previously-included directories found matching '.git*' 17:19:55 INFO - Successfully installed jsonschema functools32 17:19:55 INFO - Cleaning up... 17:19:55 INFO - Return code: 0 17:19:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:19:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:19:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:19:55 INFO - Using env: {'DISPLAY': ':0', 17:19:55 INFO - 'HOME': '/home/cltbld', 17:19:55 INFO - 'LANG': 'en_US.UTF-8', 17:19:55 INFO - 'LANGUAGE': 'en_US:en', 17:19:55 INFO - 'LOGNAME': 'cltbld', 17:19:55 INFO - 'MAIL': '/var/mail/cltbld', 17:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:55 INFO - 'MOZ_NO_REMOTE': '1', 17:19:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:55 INFO - 'NO_EM_RESTART': '1', 17:19:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:55 INFO - 'PWD': '/builds/slave/test', 17:19:55 INFO - 'SHELL': '/bin/bash', 17:19:55 INFO - 'SHLVL': '1', 17:19:55 INFO - 'TERM': 'linux', 17:19:55 INFO - 'TMOUT': '86400', 17:19:55 INFO - 'USER': 'cltbld', 17:19:55 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:55 INFO - '_': '/tools/buildbot/bin/python'} 17:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:19:56 INFO - Cleaning up... 17:19:56 INFO - Return code: 0 17:19:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:56 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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19:56 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 17:19:56 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 17:19:56 INFO - Using env: {'DISPLAY': ':0', 17:19:56 INFO - 'HOME': '/home/cltbld', 17:19:56 INFO - 'LANG': 'en_US.UTF-8', 17:19:56 INFO - 'LANGUAGE': 'en_US:en', 17:19:56 INFO - 'LOGNAME': 'cltbld', 17:19:56 INFO - 'MAIL': '/var/mail/cltbld', 17:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:56 INFO - 'MOZ_NO_REMOTE': '1', 17:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:56 INFO - 'NO_EM_RESTART': '1', 17:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:56 INFO - 'PWD': '/builds/slave/test', 17:19:56 INFO - 'SHELL': '/bin/bash', 17:19:56 INFO - 'SHLVL': '1', 17:19:56 INFO - 'TERM': 'linux', 17:19:56 INFO - 'TMOUT': '86400', 17:19:56 INFO - 'USER': 'cltbld', 17:19:56 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:56 INFO - '_': '/tools/buildbot/bin/python'} 17:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:56 INFO - Downloading/unpacking blobuploader==1.2.4 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:58 INFO - Downloading blobuploader-1.2.4.tar.gz 17:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:19:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:19:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19:59 INFO - Downloading docopt-0.6.1.tar.gz 17:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:19:59 INFO - Installing collected packages: blobuploader, requests, docopt 17:19:59 INFO - Running setup.py install for blobuploader 17:19:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:19:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:19:59 INFO - Running setup.py install for requests 17:19:59 INFO - Running setup.py install for docopt 17:19:59 INFO - Successfully installed blobuploader requests docopt 17:19:59 INFO - Cleaning up... 17:19:59 INFO - Return code: 0 17:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:59 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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:19:59 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 17:19:59 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 17:19:59 INFO - Using env: {'DISPLAY': ':0', 17:19:59 INFO - 'HOME': '/home/cltbld', 17:19:59 INFO - 'LANG': 'en_US.UTF-8', 17:19:59 INFO - 'LANGUAGE': 'en_US:en', 17:19:59 INFO - 'LOGNAME': 'cltbld', 17:19:59 INFO - 'MAIL': '/var/mail/cltbld', 17:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:59 INFO - 'MOZ_NO_REMOTE': '1', 17:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:59 INFO - 'NO_EM_RESTART': '1', 17:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:59 INFO - 'PWD': '/builds/slave/test', 17:19:59 INFO - 'SHELL': '/bin/bash', 17:19:59 INFO - 'SHLVL': '1', 17:19:59 INFO - 'TERM': 'linux', 17:19:59 INFO - 'TMOUT': '86400', 17:19:59 INFO - 'USER': 'cltbld', 17:19:59 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:59 INFO - '_': '/tools/buildbot/bin/python'} 17:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:19:59 INFO - Running setup.py (path:/tmp/pip-9Zl6vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:19:59 INFO - Running setup.py (path:/tmp/pip-sWHDx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:00 INFO - Running setup.py (path:/tmp/pip-y_qpFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:00 INFO - Running setup.py (path:/tmp/pip-bim2CV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:00 INFO - Running setup.py (path:/tmp/pip-8rkx5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:00 INFO - Running setup.py (path:/tmp/pip-oGIbci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:00 INFO - Running setup.py (path:/tmp/pip-DnFpuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:00 INFO - Running setup.py (path:/tmp/pip-OLY9F_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:00 INFO - Running setup.py (path:/tmp/pip-2Q1ci1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:00 INFO - Running setup.py (path:/tmp/pip-R7aoPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:00 INFO - Running setup.py (path:/tmp/pip-EoSXYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:00 INFO - Running setup.py (path:/tmp/pip-KWrp0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:00 INFO - Running setup.py (path:/tmp/pip-CkO9Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:00 INFO - Running setup.py (path:/tmp/pip-vy9eOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:00 INFO - Running setup.py (path:/tmp/pip-ZVS6ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:00 INFO - Running setup.py (path:/tmp/pip-Uem2wT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:01 INFO - Running setup.py (path:/tmp/pip-5PGa_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:20:01 INFO - Running setup.py install for manifestparser 17:20:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozcrash 17:20:01 INFO - Running setup.py install for mozdebug 17:20:01 INFO - Running setup.py install for mozdevice 17:20:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozfile 17:20:01 INFO - Running setup.py install for mozhttpd 17:20:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozinfo 17:20:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozInstall 17:20:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozleak 17:20:02 INFO - Running setup.py install for mozlog 17:20:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for moznetwork 17:20:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for mozprocess 17:20:02 INFO - Running setup.py install for mozprofile 17:20:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for mozrunner 17:20:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for mozscreenshot 17:20:02 INFO - Running setup.py install for moztest 17:20:02 INFO - Running setup.py install for mozversion 17:20:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:20:02 INFO - Cleaning up... 17:20:02 INFO - Return code: 0 17:20:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:02 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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:20:02 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 17:20:02 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 17:20:02 INFO - Using env: {'DISPLAY': ':0', 17:20:02 INFO - 'HOME': '/home/cltbld', 17:20:02 INFO - 'LANG': 'en_US.UTF-8', 17:20:02 INFO - 'LANGUAGE': 'en_US:en', 17:20:02 INFO - 'LOGNAME': 'cltbld', 17:20:02 INFO - 'MAIL': '/var/mail/cltbld', 17:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:02 INFO - 'MOZ_NO_REMOTE': '1', 17:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:02 INFO - 'NO_EM_RESTART': '1', 17:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:02 INFO - 'PWD': '/builds/slave/test', 17:20:02 INFO - 'SHELL': '/bin/bash', 17:20:02 INFO - 'SHLVL': '1', 17:20:02 INFO - 'TERM': 'linux', 17:20:02 INFO - 'TMOUT': '86400', 17:20:02 INFO - 'USER': 'cltbld', 17:20:02 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:02 INFO - '_': '/tools/buildbot/bin/python'} 17:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:03 INFO - Running setup.py (path:/tmp/pip-kbW2aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:03 INFO - Running setup.py (path:/tmp/pip-qzItxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:03 INFO - Running setup.py (path:/tmp/pip-e2POBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:03 INFO - Running setup.py (path:/tmp/pip-ALXcdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:03 INFO - Running setup.py (path:/tmp/pip-kYNzJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:03 INFO - Running setup.py (path:/tmp/pip-tEF5iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:03 INFO - Running setup.py (path:/tmp/pip-k5SENI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:03 INFO - Running setup.py (path:/tmp/pip-UPwwQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:03 INFO - Running setup.py (path:/tmp/pip-cFVv7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:03 INFO - Running setup.py (path:/tmp/pip-nLhZy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:03 INFO - Running setup.py (path:/tmp/pip-N5ssc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:03 INFO - Running setup.py (path:/tmp/pip-xkCOru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:04 INFO - Running setup.py (path:/tmp/pip-32fjE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:04 INFO - Running setup.py (path:/tmp/pip-8Bu9KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:04 INFO - Running setup.py (path:/tmp/pip-UdOQZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:04 INFO - Running setup.py (path:/tmp/pip-bqN1cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:04 INFO - Running setup.py (path:/tmp/pip-g07bkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:04 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)) 17:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:04 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)) 17:20:04 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:04 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:20:04 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 17:20:04 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 17:20:04 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 17:20:04 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 17:20:04 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 17:20:04 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 17:20:06 INFO - Downloading blessings-1.6.tar.gz 17:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:20:06 INFO - Installing collected packages: blessings 17:20:06 INFO - Running setup.py install for blessings 17:20:06 INFO - Successfully installed blessings 17:20:06 INFO - Cleaning up... 17:20:06 INFO - Return code: 0 17:20:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:06 INFO - Reading from file tmpfile_stdout 17:20:06 INFO - Current package versions: 17:20:06 INFO - argparse == 1.2.1 17:20:06 INFO - blessings == 1.6 17:20:06 INFO - blobuploader == 1.2.4 17:20:06 INFO - docopt == 0.6.1 17:20:06 INFO - functools32 == 3.2.3-2 17:20:06 INFO - jsonschema == 2.5.1 17:20:06 INFO - manifestparser == 1.1 17:20:06 INFO - mozInstall == 1.12 17:20:06 INFO - mozcrash == 1.0 17:20:06 INFO - mozdebug == 0.1 17:20:06 INFO - mozdevice == 0.49 17:20:06 INFO - mozfile == 1.2 17:20:06 INFO - mozhttpd == 0.7 17:20:06 INFO - mozinfo == 0.9 17:20:06 INFO - mozleak == 0.1 17:20:06 INFO - mozlog == 3.4 17:20:06 INFO - moznetwork == 0.27 17:20:06 INFO - mozprocess == 0.24 17:20:06 INFO - mozprofile == 0.28 17:20:06 INFO - mozrunner == 6.13 17:20:06 INFO - mozscreenshot == 0.1 17:20:06 INFO - mozsystemmonitor == 0.3 17:20:06 INFO - moztest == 0.8 17:20:06 INFO - mozversion == 1.4 17:20:06 INFO - psutil == 3.1.1 17:20:06 INFO - requests == 1.2.3 17:20:06 INFO - wsgiref == 0.1.2 17:20:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:06 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 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:20:06 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 17:20:06 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 17:20:06 INFO - Using env: {'DISPLAY': ':0', 17:20:06 INFO - 'HOME': '/home/cltbld', 17:20:06 INFO - 'LANG': 'en_US.UTF-8', 17:20:06 INFO - 'LANGUAGE': 'en_US:en', 17:20:06 INFO - 'LOGNAME': 'cltbld', 17:20:06 INFO - 'MAIL': '/var/mail/cltbld', 17:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:06 INFO - 'MOZ_NO_REMOTE': '1', 17:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:06 INFO - 'NO_EM_RESTART': '1', 17:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:06 INFO - 'PWD': '/builds/slave/test', 17:20:06 INFO - 'SHELL': '/bin/bash', 17:20:06 INFO - 'SHLVL': '1', 17:20:06 INFO - 'TERM': 'linux', 17:20:06 INFO - 'TMOUT': '86400', 17:20:06 INFO - 'USER': 'cltbld', 17:20:06 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:06 INFO - '_': '/tools/buildbot/bin/python'} 17:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 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)) 17:20:07 INFO - Cleaning up... 17:20:07 INFO - Return code: 0 17:20:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xad3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd35630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, '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': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', '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 17:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:20:07 INFO - Using env: {'DISPLAY': ':0', 17:20:07 INFO - 'HOME': '/home/cltbld', 17:20:07 INFO - 'LANG': 'en_US.UTF-8', 17:20:07 INFO - 'LANGUAGE': 'en_US:en', 17:20:07 INFO - 'LOGNAME': 'cltbld', 17:20:07 INFO - 'MAIL': '/var/mail/cltbld', 17:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:07 INFO - 'MOZ_NO_REMOTE': '1', 17:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:07 INFO - 'NO_EM_RESTART': '1', 17:20:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:07 INFO - 'PWD': '/builds/slave/test', 17:20:07 INFO - 'SHELL': '/bin/bash', 17:20:07 INFO - 'SHLVL': '1', 17:20:07 INFO - 'TERM': 'linux', 17:20:07 INFO - 'TMOUT': '86400', 17:20:07 INFO - 'USER': 'cltbld', 17:20:07 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:07 INFO - '_': '/tools/buildbot/bin/python'} 17:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:20:07 INFO - Cleaning up... 17:20:07 INFO - Return code: 0 17:20:07 INFO - Running post-action listener: _resource_record_post_action 17:20:07 INFO - Running post-action listener: _start_resource_monitoring 17:20:07 INFO - Starting resource monitoring. 17:20:07 INFO - [mozharness: 2017-02-10 01:20:07.357529Z] Finished create-virtualenv step (success) 17:20:07 INFO - [mozharness: 2017-02-10 01:20:07.357972Z] Running install step. 17:20:07 INFO - Running pre-action listener: _resource_record_pre_action 17:20:07 INFO - Running main action method: install 17:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:07 INFO - Reading from file tmpfile_stdout 17:20:07 INFO - Detecting whether we're running mozinstall >=1.0... 17:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:20:07 INFO - Reading from file tmpfile_stdout 17:20:07 INFO - Output received: 17:20:07 INFO - Usage: mozinstall [options] installer 17:20:07 INFO - Options: 17:20:07 INFO - -h, --help show this help message and exit 17:20:07 INFO - -d DEST, --destination=DEST 17:20:07 INFO - Directory to install application into. [default: 17:20:07 INFO - "/builds/slave/test"] 17:20:07 INFO - --app=APP Application being installed. [default: firefox] 17:20:07 INFO - mkdir: /builds/slave/test/build/application 17:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:20:24 INFO - Reading from file tmpfile_stdout 17:20:24 INFO - Output received: 17:20:24 INFO - /builds/slave/test/build/application/firefox/firefox 17:20:24 INFO - Running post-action listener: _resource_record_post_action 17:20:24 INFO - [mozharness: 2017-02-10 01:20:24.138163Z] Finished install step (success) 17:20:24 INFO - [mozharness: 2017-02-10 01:20:24.138437Z] Running run-tests step. 17:20:24 INFO - Running pre-action listener: _resource_record_pre_action 17:20:24 INFO - Running main action method: run_tests 17:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:20:24 INFO - Python 2.7.3 17:20:24 INFO - Return code: 0 17:20:24 INFO - grabbing minidump binary from tooltool 17:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:24 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 0xdbe850>, '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 0xd336d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd33b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:20:24 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 17:20:24 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 17:20:24 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 17:20:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:20:24 INFO - Return code: 0 17:20:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:20:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:20:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:20:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:20:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--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 17:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-076 --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 17:20:24 INFO - Using env: {'DISPLAY': ':0', 17:20:24 INFO - 'HOME': '/home/cltbld', 17:20:24 INFO - 'LANG': 'en_US.UTF-8', 17:20:24 INFO - 'LANGUAGE': 'en_US:en', 17:20:24 INFO - 'LOGNAME': 'cltbld', 17:20:24 INFO - 'MAIL': '/var/mail/cltbld', 17:20:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:20:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:24 INFO - 'MOZ_NO_REMOTE': '1', 17:20:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:20:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:24 INFO - 'NO_EM_RESTART': '1', 17:20:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:24 INFO - 'PWD': '/builds/slave/test', 17:20:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:20:24 INFO - 'SHELL': '/bin/bash', 17:20:24 INFO - 'SHLVL': '1', 17:20:24 INFO - 'TERM': 'linux', 17:20:24 INFO - 'TMOUT': '86400', 17:20:24 INFO - 'USER': 'cltbld', 17:20:24 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680', 17:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:24 INFO - '_': '/tools/buildbot/bin/python'} 17:20:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--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 17:20:24 INFO - mozversion application_buildid: 20170210002235 17:20:24 INFO - mozversion application_changeset: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 17:20:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:20:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:20:24 INFO - mozversion application_name: Firefox 17:20:24 INFO - mozversion application_remotingname: firefox-dev 17:20:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:20:24 INFO - mozversion application_vendor: Mozilla 17:20:24 INFO - mozversion application_version: 53.0a2 17:20:24 INFO - mozversion platform_buildid: 20170210002235 17:20:24 INFO - mozversion platform_changeset: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 17:20:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:20:24 INFO - mozversion platform_version: 53.0a2 17:20:24 INFO - using testdate: 1486689624 17:20:24 INFO - actual date: 1486689624 17:20:24 INFO - starting webserver on 'localhost:33482' 17:20:24 INFO - SUITE-START | Running 2 tests 17:20:24 INFO - TEST-START | dromaeo_css 17:20:24 INFO - operating with platform_type : linux_ 17:20:24 INFO - Initialising browser for dromaeo_css test... 17:20:24 INFO - TEST-INFO | started process 9696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa7Ocey/profile http://localhost:33482/getInfo.html) 17:20:28 INFO - PROCESS | 9696 | __metrics Screen width/height:1600/1200 17:20:28 INFO - PROCESS | 9696 | colorDepth:24 17:20:28 INFO - PROCESS | 9696 | Browser inner width/height: 1024/697 17:20:28 INFO - PROCESS | 9696 | __metrics 17:20:29 INFO - PROCESS | 9696 | 1486689629267 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:20:29 INFO - PROCESS | 9696 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:20:29 INFO - PROCESS | 9696 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:20:30 INFO - TEST-INFO | 9696: exit 0 17:20:30 INFO - Browser initialized. 17:20:30 INFO - Running cycle 1/1 for dromaeo_css test... 17:20:30 INFO - TEST-INFO | started process 9789 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa7Ocey/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:20:42 INFO - PROCESS | 9789 | RSS: Main: 137887744 17:20:42 INFO - PROCESS | 9789 | 17:23:42 INFO - PROCESS | 9789 | Cycle 1(1): loaded http://localhost:33482/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33482/tests/dromaeo/cssquery-ext.html) 17:23:43 INFO - PROCESS | 9789 | RSS: Main: 242024448 17:23:43 INFO - PROCESS | 9789 | 17:25:32 INFO - PROCESS | 9789 | ************************* 17:25:32 INFO - PROCESS | 9789 | A coding exception was thrown and uncaught in a Task. 17:25:32 INFO - PROCESS | 9789 | 17:25:32 ERROR - PROCESS | 9789 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:25:32 INFO - PROCESS | 9789 | Full stack: 17:25:32 INFO - PROCESS | 9789 | ************************* 17:26:46 INFO - PROCESS | 9789 | Cycle 1(1): loaded http://localhost:33482/tests/dromaeo/cssquery-ext.html (next: http://localhost:33482/tests/dromaeo/cssquery-jquery.html) 17:26:46 INFO - PROCESS | 9789 | RSS: Main: 227405824 17:26:46 INFO - PROCESS | 9789 | 17:29:48 INFO - PROCESS | 9789 | Cycle 1(1): loaded http://localhost:33482/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33482/tests/dromaeo/cssquery-mootools.html) 17:29:49 INFO - PROCESS | 9789 | RSS: Main: 171208704 17:29:49 INFO - PROCESS | 9789 | 17:32:51 INFO - PROCESS | 9789 | Cycle 1(1): loaded http://localhost:33482/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33482/tests/dromaeo/cssquery-prototype.html) 17:32:51 INFO - PROCESS | 9789 | RSS: Main: 194473984 17:32:51 INFO - PROCESS | 9789 | 17:35:54 INFO - PROCESS | 9789 | Cycle 1(1): loaded http://localhost:33482/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33482/tests/dromaeo/cssquery-yui.html) 17:35:54 INFO - PROCESS | 9789 | RSS: Main: 172728320 17:35:54 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | Cycle 1(1): loaded http://localhost:33482/tests/dromaeo/cssquery-yui.html (next: http://localhost:33482/tests/dromaeo/cssquery-dojo.html) 17:38:57 INFO - PROCESS | 9789 | RSS: Main: 259813376 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | __start_tp_report 17:38:57 INFO - PROCESS | 9789 | _x_x_mozilla_page_load 17:38:57 INFO - PROCESS | 9789 | _x_x_mozilla_page_load_details 17:38:57 INFO - PROCESS | 9789 | |i|pagename|runs| 17:38:57 INFO - PROCESS | 9789 | |0;dojo.html;3323.484859846724;3341.599008119026;3356.3287342531494;3365.814880181589;3393.202597389613;5372.9462705372935;5417.404085550591;5444.945550544493;5449.209864569635;5458.4814442627885;5442.319710036246;5506.091494903336;5510.1459273812625;5527.778888844451;5553.8333849984465;4905.141600219974;4914.901701965959;4945.876353091166;4958.082754690394;5002.324686167375;4808.158572249859;4943.307936888838;4975.378077740283;4996.625253106014;5028.296038554606;4783.28250762385;4854.101991131652;4894.36373271476;4897.902041959159;4995.325631039799;4956.553910148086;4993.525615066563;4993.800247990094;4994.55049045584;5007.774650140753;4922.26166075088;4923.113839508923;4955.0833095877015;4971.6271279654175;4977.701337919736;5238.397584277781;5323.627346085776;5338.652987555796;5384.6769193848495;5409.404965453797;5844.211031510734;5849.356570777211;5852.356240813507;5858.355580886099;5875.9118613220835;5876.29484461863;5923.703814809242;5924.97037514814;5927.4665280125;5939.168516407706;5368.570514358842;5411.080116380264;5430;5481.643693159932;5484.698341591171;5993.850153746104;6003.699815009232;6009.369016253318;6025.0661147522205;6047.848803779941;5790.768369265266;5794.797182098607;5794.898969195923;5799.4780469757925;5806.1581070744505;5719.456651618089;5751.741171647286;5827.76688932438;5896.1450589664255;5898.675572843449;5692.971535142381;5728.971355143281;5761.654300741969;5782.479576838021;5813.796517121965;5802.680852553066;5803.912941305884;5809.448102430262;5811.215485909433;5813.82558523245;4417.0724147929295;4419.359192917009;4420.84527041552;4424.690271680952;4449.7330160190495;3897.9610203898164;3923.1172986078473;3970.940435893406;3990.2618015667194;4014.237294913957;5713;5722.027255366597;5726.541876649858;5727.31272247333;5758.021136406821;5761;5779.537636989031;5810.680412577349;5876.44173803488;5882.911661342665;5647.435256474319;5694.487496125369;5723.170140329712;5735.0250457422335;5748.109043098327;5028.32117664118;5036.294918711384;5041.143005689041;5042.924356134661;5050.924236136461;5984.940150598438;6029.939700602938;6176;6183.84540386485;6183.84540386494;5791.160281759206;5849.970750146222;5850.64896106235;5918.7928422505;5958.285005799332;6184.412480814315;6190.164327815867;6198.473129784019;6205.968970155121;6208.317085120543;5600.747966341443;5759.94240057594;5827.125931110368;5835.649861008353;5837.182794408871;5768.653880767168;5774.162746401832;5797.217375654402;5798.275215598051;5922.170896074467;5752.9712351437975;5757.769689212385;5760.135979603093;5761.107028410603;5761.135829625589;5748.597598168088;5751.626144300607;5754.4533269339345;5761.711914404347;5762.078067509178;5559.805406810742;5574.442555744393;5644.6330988485615;5655.208270842168;5678.545716342765;5769.91345129815;5781.479666830005;5784.305883294031;5784.537237021113;5784.595078344475;5615.129654903497;5636.633618814764;5656.2929633795775;5659.915101273402;5667.1782591524825 17:38:57 INFO - PROCESS | 9789 | |1;ext.html;546.635743956194;583.8540364908772;603.6076550242343;628.1018144054004;672.2000819025362;36288.63711362886;45940;47115.057698846;47119.76440117803;47140.76429617856;48724.26913596299;48806.75596622021;48848.75575622126;48952.51047489524;49393.75303123488;8763.56182190888;9284.303677224218;9459.337846350758;9476.81046379072;9477.62089516422;8787.297016238686;11011.559537618527;11240.213185077047;11245.381504017276;11612.709682257926;11361.659150225507;11430.142739294544;11440.084793216563;11440.256383335056;11441.485133168986;11254.043406310473;11275.66173014811;11279.943600282028;11288.379139147344;11354.659360219206;11165.330080195214;11243.662690119229;11253.718657033558;11265.662030139109;11333.15001374892;47213.527864721604;47371;47481;47515.28727069097;47539.76230118862;4349.499807522144;4484.349769283435;4496.595306422438;4502.752348620825;4655.930161047587;32172.678273217203;32366.676333236603;32725.18187045319;33012.339753204804;33747.15632109217;4656.348111264426;4661.324108004321;4685.605361250705;4701.858944231679;4772.713637181745;360958;362906.1854690736;363323;363338;364785;61674.38325616686;61930.380696193366;62022.37977620166;62056.68971655203;62086.689566551875;57908.71045644745;58187;58232.41767582355;58322;58357;16691.33234670624;16804.327826886787;16863.831361686473;16892.324307027582;16961.91519042397;12438;12564.057695672862;13426.865731342757;13613.591592252249;13715.657108572366;18279.360222392417;19155.37179339744;19180.273775360285;19264.90367548153;19477.80522194788;7086.834454025031;7091.787246382617;7289.198188199294;7304.415646748247;7341.77974660761;1001.4592120255127;1006.7634105985088;1010.1161483701761;1019.6686077024997;1031.7575369788094;33893.66106338905;33901.66098338985;34037.14907127292;34321;34639.65360346415;1151.9020883225019;1186.1578279421535;1187.2994932989577;1189.8453201083803;1218.4090716002731;17234.91382543079;17913.283468661106;18141.45575632733;18213.08934553267;18228.45314640563;530593;532171;532669;534709.3264533653;535065;68636;69271.30728692749;69324.30675693178;69440.6527967357;69626;1499.2803454341977;1520.5894408509862;1534.4936171063523;1543.5909483986752;1545.4281915691042;31563.210919726273;33224;33632.49551256684;33774;34158.31683366369;43414.13171736484;43728.34407483954;43781.12437751291;43904.78047609742;43971.34042989422;394340;394903;395978;395986;398277;28933.13200603901;29606.259843504085;30331;30336.393272134876;31498.842505787325;45863.54136458593;50823;51176.74411627918;51288.48711512837;51443.228351574006;38227.617723822405;38541.2291754169;39208;42836.357454638826;44192.77903610461;39280.607193927695;39816.800915995234;40521.18957620772;41074.38388424236;42643.78678106589;40385.39421908615;41224.175516488904;41333;41367.793161034;41628.79185604052;34488.82755586206;36855.63144368522;37194;37338.8133059333;37414.438783417725;27299.454010919275;27594.724052759215;27767.583486248128;27987.440251194454;28026.159215223575 17:38:57 INFO - PROCESS | 9789 | |2;jquery.html;405.38786432486955;412.92360913231033;422.16200841329953;424.89165262857966;437.1519252649859;20133.899330503344;20648.38054858353;21836.8908155459;21991.670124948138;22075.779242207595;21457.892710536424;21905.123795048177;21922;22068.22761203354;22257;5982.192404025455;6199.938000620004;6210.28581713102;6213.34759850216;6213.689315534228;5862.50168735658;5992.0712289595185;6008.128821320905;6016.8495787605225;6043.546733994946;5977.222961015062;5982.162497250389;5990.311114221877;6000.75996960121;6005.12925625784;5833.212516310286;5944.346121926585;5964.7315870785915;5980.491658209035;5983.670898100603;5260.210968354739;5298.8079417656545;5335.653182543161;5351.063563876345;5353.197020446925;24587;24596.877015614988;24843;24871.5025699485;25060.122895698747;3190.457622204231;3232;3279.8688052477873;3300.372929143455;3313.105461525399;15172.089674619558;15309.234538273042;15510.61223469411;15603.297851596706;15719.449819256386;1880.2666959885655;1890.886546807196;1902.581432084953;1930.5463216144194;1957.3834242213657;68130;70715.2928470719;71329.64335178342;91887;93065.06934930632;9136.862947055799;10409.323393979366;11026.44867756609;11174.10607151426;11289.137292346672;16483.175841207893;16567.088810115558;16668.83331166697;16744.66510669804;16756.16219189036;8736.257418119403;8754.606042728092;8824.117588241124;9018.503982281038;9041.412308199946;8587.914120858837;8646.43798153118;8786.297096232285;8803.075677053945;8832.337574681796;7290.869915163158;7345.325903261262;7349.926500734924;7519.135299440525;7526.209747976493;1923.422973108062;1976.8813871167777;1978.5844972555924;1994.3418671838258;2115.915363385478;957.6887511558771;973.0804389851522;975.1467465967279;980.2599037726471;990.5146478225618;20019.899900500695;20362.796372036388;20756.79243207579;20837.68743468849;21151.365459036253;973.1922504321396;977.9266555008261;978.9657361992439;981.1415011864618;990.9355891867006;11456.140789440826;11725.589604363806;11880.940595296968;12002.879971200176;12008.63974080779;648209;657679;676157;676330;676825;86804.56597717098;89549;90306.54846725856;91925.5403722977;92028;1123.5898896902736;1144.164759725405;1153.4463457540428;1155.589765633205;1160.9482904662666;17871.73192402141;17894.91052544729;18239.452816415527;18252.36116735908;18559.35042273514;42770;43291.35062974121;43584.564154359316;43818.12363752771;43857.56142438535;56736.43263567312;57182.7140864293;57493.71253143708;57496.12519374098;57883;7678.424118191047;8270.007599088149;8953.865692014495;8958.641654333753;9067.455952642862;13464.528741494392;13609.659758506117;13685.86314136846;13714.925269653177;14016.439342426189;11064.557417703523;11317.773644527228;11412.828807567726;11733.941330293294;12236.510539578318;10369.325993810378;11103.111751060058;11394.715132121763;11602.535898563963;11623.88376116228;11219.943900280447;11386.202965792316;12200.75598488043;12324.08976608627;12366.44351004189;8230.958845205736;8345.83308333842;8485.490870547786;8564.400491965753;8897.91102088971;10497.2127090467;11390.943045284721;11490.48292826808;12163.81754273669;12529.874701252871 17:38:57 INFO - PROCESS | 9789 | |3;mootools.html;1464.9163510809744;1482.2070192447043;1482.4589025005876;1494.4545240987043;1497.4609140709345;7885.802854928623;8388;8388.119247479019;8390.160983901607;8396.91603083969;7336.889946650791;7525.322720955113;7533.171351151369;7882.842343153134;8249.422540422172;2383.9046438142495;2430.598951173063;2431.890564924574;2440.7803297703203;2440.865752383618;2400.9039638414442;2413.75862413759;2423.5758742220128;2429.7813196812285;2434.026389444228;2347.95304093918;2364.326167042393;2372.418757404438;2378.583747844129;2391.0674836813655;2297.0926484038796;2298.689676893615;2321.6401457774073;2326.790588847012;2327.8486898351553;2360.114956891166;2376.6316220985773;2382.106709983756;2383.0467812874817;2386.6300723387903;8090.797730056737;8186.713465028695;8242.835143297161;8274.710385136552;8288.21261980111;5125.948740512622;5259.395169555473;5272.077386457355;5277.577793776489;5279.392869819943;3282.2943067240417;3373.409653310661;3431.039308993486;3557.7865328080397;3559.501669766235;1436.1885534672876;1455.185096346039;1475.0559641829132;1492.7760835874597;1500.5123334916086;10861.022507974241;10861.67414977552;10873.347599143997;10878.456077196108;10887.945560272226;4557.475890272594;4644.094401591725;4671.8364857229835;4675.696079754805;4723.267893476516;9556.426614403159;9558.139767420966;9576.952115239379;9584.568694408768;9589.376690515233;5309.867253318698;5319;5346.572274218053;5353.036453438341;5355.919661205085;4694.718316900997;4776.498467660915;4849;4911.238757992517;4922.507749225048;3891.1633998690418;4012.3580227163516;4025.054112283611;4087.0599762054894;4126.319157339066;1281.9935900320438;1284.6338793443824;1285.5307812648316;1295.1775622479793;1299.1620404838825;966.1353088985459;970.2722957781664;972.5088830140734;988.0761488008735;1005.6379703306803;5222.216667499905;5333.7066461345;5386.8383948481605;5405.378381486101;5420.647657902303;610.7709608896664;615.2524682510771;627.8744251149789;631.2771876201815;641.7272659119856;3436.1409647588102;3449.6722811332884;3452.050686061353;3457.187560923181;3522.4716292556323;9329.580168892417;9646.9035309646;9678.56446459911;9693.127618514367;9703.951480242553;9501.619935202652;9598.184154346958;9630.085141911508;9772.462514561767;9787.804243915081;7414.25857414265;7691.038620172479;7713.65288562016;7714.151443341228;7722.768316950501;5374.220737993047;5386.676799392049;5415.160650099241;5447.428020057837;5550.27846379968;8534.914650853412;8648.956755216184;8684.522351270742;8784.341174411817;8839.646414143363;8047.798805029921;8450.239478446723;8973.506457144658;8983.416077954913;9067.09329067088;7062.505624606229;7320.816979575553;7321.194668586558;7322;7331.890021649786;7753.92246077532;7842.64708088126;7893.36853051766;7921.603919803872;7947.3244774194845;7125.287471252833;7167.820804479929;7205.495615306878;7242.710291588278;7360.411167106511;7100.147982242164;7209.206987231505;7217.170025447141;7222.96388518054;7251.274872512707;6377.4579160771855;6378.649174295459;6401.263854656774;6467.741290348522;6472.838179045373;4963.602911766978;5101.285819985131;5140.922886156636;5154.1237989542615;5194.3247377840635;4997.700137991732;5084.618653600921;5160.277561141368;5240.816571419982;5275.788968441219 17:38:57 INFO - PROCESS | 9789 | |4;prototype.html;391.8334707749206;398.8065788092775;398.8444506642409;406.48985523168403;414.88383252689255;5408.67547947123;5415.620906536544;5427.864303392417;5429.267048948395;5433.935104660233;5102.668326558781;5199.428062913086;5269.235960785683;5290.153575427932;5360.437154098822;4223.387608796723;4241.87274381769;4244.724092933965;4245.915081698372;4271.231178387889;4234.25900467417;4237.8769626049125;4243.978780106095;4262.403263543107;4274.145170965804;4133.575447978614;4176.770277634729;4185.246655601991;4212.852550160745;4240.766757828318;4194.266003449384;4195.307774217251;4201;4211.368294755781;4214.494260688722;3827.445020472016;3844.730868839182;3857.787821669807;3859.6912247020173;3871.9806400968096;5024.84925452237;5215.165573508259;5245.400152953348;5270.5520030797425;5276.153346328792;4855.4023597375235;4856.708597484162;4881.974785295092;4911.50884911506;4925.310456536088;5549;5553.972230138864;5564.944350556483;5566.916496252519;5590.413006634326;4143.7306575072525;4247.320428731388;4255.340422234559;4278.529361770203;4283.764392958386;5716.428357164292;5731.484166425;5741.224934633813;5797.826065218051;5818.970905145489;4335.869923902288;4354.521002689701;4359.215341238544;4395.099004704028;4429.424174857312;4391.692581519263;4468.843590474317;4473.888152796206;4495.662825288116;4502.581686768723;4896.46138924718;4899.191633380452;4908.803647854046;4916.164252077156;4945.703257804544;5653.491185793298;5662.924044431545;5667.319921609434;5671.914921276184;5701.344345400332;1345.6837643153851;1396.7066915947562;1499.7926669031535;1505.9924700376428;1514.1520748380942;970.315927271272;974.7514383832078;982.2289502740457;985.5762022330467;985.8866230383454;5390.97304513475;5410.269613602113;5414.83755487336;5436.53789427903;5439.782408703608;5415.512603865671;5472.753726082336;5576.358718747315;5578.944210557924;5579.8884022320135;5257.868553286198;5371.086915224421;5382.67703937765;5404.189371594213;5495.6153069284765;2906.346072133795;2935.486289899302;2956.8817247309867;2966.1991262359043;2980.791344605857;5879.942020292949;5890.349342795759;5894.292684877756;5902.675352855634;5914.319853216849;5480.890382192413;5662.37713851476;5744.425557444222;5745.798897038583;5756.971215143897;5823.534117270693;5827.88344233113;5831.075745004138;5832.416758324219;5938.346781854079;5328.413874473882;5565.5825813063375;5567.331920169444;5579.386267510489;5621.690807005491;5485.84797192578;5506.944930550803;5516.255305533701;5517.69652669107;5518;5854.765809367577;5869.4717475427415;5871.442212989759;5873.177755114373;5880.529557635465;3928.9607103928593;4020.798960052044;4034.3948407739076;4047.838086476626;4055.1889622075114;5507.339119305709;5531.170324451365;5621.414149491116;5698.20225168485;5721.685307308138;5527.861803454945;5565.8051968182535;5574.331080270394;5593.161025846156;5610.971945140248;5419.295491586068;5522.91715624274;5534.557235421237;5540.4209800206745;5553.278483670075;5525.696086715108;5531.280933478622;5531.308586426697;5543.223948647274;5553.6390134641115;5558.888822223614;5560.916586251128;5603.075492543685;5612.522935550524;5624.8875022498505;5205.5835533158015;5454.809081682122;5476.315460567429;5499.312585926759;5519.889602207853 17:38:57 INFO - PROCESS | 9789 | |5;yui.html;819.692615269274;833.4707460762418;838.5807096451774;840.5293035899897;846.4963346808648;16145.515634530968;16673.166341682914;17071.74392384112;17336.826631733693;17416.82583174168;18153.547716182708;18716.43850684478;18756.155972981214;19003.61992760144;19038.714419283722;806.209914284002;812.772423721359;813.308687615528;813.3168138763438;813.4996976859219;714.656964656965;725.3544345532454;790.9525428474282;806.3388021822108;815.7715839564931;875.8905136857893;877.675260153744;878.8462019146656;879.2614204068581;880.3485420788602;875.4965894610592;876.0363600039968;876.7632739160423;877.1491653096487;877.5129802959354;547.5400663442728;553.6069390732584;619.201230412767;626.28603392133;627.7959663109508;16571.42000029993;16644.50066498007;16665;16682.416115436023;16689.749653755083;15354.30905609239;16125.354985800554;16131.838681613151;16201;16945.237464314014;13048.54330098442;13115.409806558728;13169.275689837055;13239.073264871462;13355.53255636048;3955.0903292242947;3963.4649322341693;3978.8806335809973;3994.360902255625;4018.9397159042637;87741;88378.11621883763;88567.55716221378;88817.55591222068;90013.64979525378;11902.226355286879;11927.284362938251;11953.820692689616;12062.215955963009;12222.816657750142;24539.87730061338;24633.63049554258;24719.134830281215;25085.749142508706;25160.496790064095;7991.360691144694;8047.155048719917;8063.516189028677;8106.594670266463;8254.380921430797;4548.840790572314;4623.8844028898875;4643.605293550018;4646.767661616906;4663.790129444184;3897.162110146332;4001.819918103634;4002.199560087936;4003.679705623543;4014.2573623879675;643.4723526708054;649.9990015376362;651.0527182948798;664.2129077043719;678.1116737074449;743.0897150989994;831.2726364431123;836.4061516323478;852.6035393542032;853.8633818588996;10512.26414151002;11444.828327575093;11447.141464390204;11747.706307342385;12011.579594714143;648.8150877000127;652.324843786688;655.1679367203625;681.3425044831761;819.2381085590458;5155.871103222449;5521.139114963091;5540.168974653834;5600;5659.151127330851;82433.58783206045;85107.57446212729;85395;85609;87188;73039.63480182565;73400.63299683575;74508.62745686345;74724.62637686777;75209.6239518799;64404;65659;65711.34288657148;66060;66764;10565.9471702641;10649.787004260026;10666.773041865079;10673.626423075155;10798.622048228588;43991.56008439875;44636;44693.77653111714;44832.775836120614;46325.305120423895;55330.446695532526;57350;58186.709066454394;58721.412785873305;58728.41271587229;12556.87443125557;12786.808197877226;12911.935440322739;13158.605241842377;13200.20798752078;13834.446622135001;14172.858271417566;14451;14812.851871481578;14922.104673719612;13287.933560332136;13473.124246923919;13498.122622029538;13579.321033948461;13788;13547;13556.322183890963;13695.315234238447;13801.102928309629;13948.860511394756;12704.87295127074;12920.676983075498;12971.481140754642;13100.541481048118;13518;11008.284461509977;11136.777264454828;11146.386948717898;11869.991050760782;11871.228370155912;10553.947230263799;10902.236942877737;11081.058110060736;11148.554057837595;11158.944205278922 17:38:57 INFO - PROCESS | 9789 | __end_tp_report 17:38:57 INFO - PROCESS | 9789 | __start_cc_report 17:38:57 INFO - PROCESS | 9789 | _x_x_mozilla_cycle_collect,942 17:38:57 INFO - PROCESS | 9789 | __end_cc_report 17:38:57 INFO - PROCESS | 9789 | __startTimestamp1486690737679__endTimestamp 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | ------- Summary: start ------- 17:38:57 INFO - PROCESS | 9789 | Number of tests: 6 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | [#0] dojo.html Cycles:165 Average:5447.75 Median:5723.17 stddev:615.59 (10.8%) stddev-sans-first:594.49 17:38:57 INFO - PROCESS | 9789 | Values: 3323.5 3341.6 3356.3 3365.8 3393.2 5372.9 5417.4 5444.9 5449.2 5458.5 5442.3 5506.1 5510.1 5527.8 5553.8 4905.1 4914.9 4945.9 4958.1 5002.3 4808.2 4943.3 4975.4 4996.6 5028.3 4783.3 4854.1 4894.4 4897.9 4995.3 4956.6 4993.5 4993.8 4994.6 5007.8 4922.3 4923.1 4955.1 4971.6 4977.7 5238.4 5323.6 5338.7 5384.7 5409.4 5844.2 5849.4 5852.4 5858.4 5875.9 5876.3 5923.7 5925.0 5927.5 5939.2 5368.6 5411.1 5430.0 5481.6 5484.7 5993.9 6003.7 6009.4 6025.1 6047.8 5790.8 5794.8 5794.9 5799.5 5806.2 5719.5 5751.7 5827.8 5896.1 5898.7 5693.0 5729.0 5761.7 5782.5 5813.8 5802.7 5803.9 5809.4 5811.2 5813.8 4417.1 4419.4 4420.8 4424.7 4449.7 3898.0 3923.1 3970.9 3990.3 4014.2 5713.0 5722.0 5726.5 5727.3 5758.0 5761.0 5779.5 5810.7 5876.4 5882.9 5647.4 5694.5 5723.2 5735.0 5748.1 5028.3 5036.3 5041.1 5042.9 5050.9 5984.9 6029.9 6176.0 6183.8 6183.8 5791.2 5850.0 5850.6 5918.8 5958.3 6184.4 6190.2 6198.5 6206.0 6208.3 5600.7 5759.9 5827.1 5835.6 5837.2 5768.7 5774.2 5797.2 5798.3 5922.2 5753.0 5757.8 5760.1 5761.1 5761.1 5748.6 5751.6 5754.5 5761.7 5762.1 5559.8 5574.4 5644.6 5655.2 5678.5 5769.9 5781.5 5784.3 5784.5 5784.6 5615.1 5636.6 5656.3 5659.9 5667.2 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | [#1] ext.html Cycles:180 Average:60428.09 Median:38384.42 stddev:115497.51 (300.9%) stddev-sans-first:115734.00 17:38:57 INFO - PROCESS | 9789 | Values: 546.6 583.9 603.6 628.1 672.2 36288.6 45940.0 47115.1 47119.8 47140.8 48724.3 48806.8 48848.8 48952.5 49393.8 8763.6 9284.3 9459.3 9476.8 9477.6 8787.3 11011.6 11240.2 11245.4 11612.7 11361.7 11430.1 11440.1 11440.3 11441.5 11254.0 11275.7 11279.9 11288.4 11354.7 11165.3 11243.7 11253.7 11265.7 11333.2 47213.5 47371.0 47481.0 47515.3 47539.8 4349.5 4484.3 4496.6 4502.8 4655.9 32172.7 32366.7 32725.2 33012.3 33747.2 4656.3 4661.3 4685.6 4701.9 4772.7 360958.0 362906.2 363323.0 363338.0 364785.0 61674.4 61930.4 62022.4 62056.7 62086.7 57908.7 58187.0 58232.4 58322.0 58357.0 16691.3 16804.3 16863.8 16892.3 16961.9 12438.0 12564.1 13426.9 13613.6 13715.7 18279.4 19155.4 19180.3 19264.9 19477.8 7086.8 7091.8 7289.2 7304.4 7341.8 1001.5 1006.8 1010.1 1019.7 1031.8 33893.7 33901.7 34037.1 34321.0 34639.7 1151.9 1186.2 1187.3 1189.8 1218.4 17234.9 17913.3 18141.5 18213.1 18228.5 530593.0 532171.0 532669.0 534709.3 535065.0 68636.0 69271.3 69324.3 69440.7 69626.0 1499.3 1520.6 1534.5 1543.6 1545.4 31563.2 33224.0 33632.5 33774.0 34158.3 43414.1 43728.3 43781.1 43904.8 43971.3 394340.0 394903.0 395978.0 395986.0 398277.0 28933.1 29606.3 30331.0 30336.4 31498.8 45863.5 50823.0 51176.7 51288.5 51443.2 38227.6 38541.2 39208.0 42836.4 44192.8 39280.6 39816.8 40521.2 41074.4 42643.8 40385.4 41224.2 41333.0 41367.8 41628.8 34488.8 36855.6 37194.0 37338.8 37414.4 27299.5 27594.7 27767.6 27987.4 28026.2 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | [#2] jquery.html Cycles:180 Average:34460.14 Median:417.54 stddev:109130.33 (26136.3%) stddev-sans-first:109406.51 17:38:57 INFO - PROCESS | 9789 | Values: 405.4 412.9 422.2 424.9 437.2 20133.9 20648.4 21836.9 21991.7 22075.8 21457.9 21905.1 21922.0 22068.2 22257.0 5982.2 6199.9 6210.3 6213.3 6213.7 5862.5 5992.1 6008.1 6016.8 6043.5 5977.2 5982.2 5990.3 6000.8 6005.1 5833.2 5944.3 5964.7 5980.5 5983.7 5260.2 5298.8 5335.7 5351.1 5353.2 24587.0 24596.9 24843.0 24871.5 25060.1 3190.5 3232.0 3279.9 3300.4 3313.1 15172.1 15309.2 15510.6 15603.3 15719.4 1880.3 1890.9 1902.6 1930.5 1957.4 68130.0 70715.3 71329.6 91887.0 93065.1 9136.9 10409.3 11026.4 11174.1 11289.1 16483.2 16567.1 16668.8 16744.7 16756.2 8736.3 8754.6 8824.1 9018.5 9041.4 8587.9 8646.4 8786.3 8803.1 8832.3 7290.9 7345.3 7349.9 7519.1 7526.2 1923.4 1976.9 1978.6 1994.3 2115.9 957.7 973.1 975.1 980.3 990.5 20019.9 20362.8 20756.8 20837.7 21151.4 973.2 977.9 979.0 981.1 990.9 11456.1 11725.6 11880.9 12002.9 12008.6 648209.0 657679.0 676157.0 676330.0 676825.0 86804.6 89549.0 90306.5 91925.5 92028.0 1123.6 1144.2 1153.4 1155.6 1160.9 17871.7 17894.9 18239.5 18252.4 18559.4 42770.0 43291.4 43584.6 43818.1 43857.6 56736.4 57182.7 57493.7 57496.1 57883.0 7678.4 8270.0 8953.9 8958.6 9067.5 13464.5 13609.7 13685.9 13714.9 14016.4 11064.6 11317.8 11412.8 11733.9 12236.5 10369.3 11103.1 11394.7 11602.5 11623.9 11219.9 11386.2 12200.8 12324.1 12366.4 8231.0 8345.8 8485.5 8564.4 8897.9 10497.2 11390.9 11490.5 12163.8 12529.9 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | [#3] mootools.html Cycles:180 Average:5410.36 Median:5349.80 stddev:2872.59 (53.7%) stddev-sans-first:2865.35 17:38:57 INFO - PROCESS | 9789 | Values: 1464.9 1482.2 1482.5 1494.5 1497.5 7885.8 8388.0 8388.1 8390.2 8396.9 7336.9 7525.3 7533.2 7882.8 8249.4 2383.9 2430.6 2431.9 2440.8 2440.9 2400.9 2413.8 2423.6 2429.8 2434.0 2348.0 2364.3 2372.4 2378.6 2391.1 2297.1 2298.7 2321.6 2326.8 2327.8 2360.1 2376.6 2382.1 2383.0 2386.6 8090.8 8186.7 8242.8 8274.7 8288.2 5125.9 5259.4 5272.1 5277.6 5279.4 3282.3 3373.4 3431.0 3557.8 3559.5 1436.2 1455.2 1475.1 1492.8 1500.5 10861.0 10861.7 10873.3 10878.5 10887.9 4557.5 4644.1 4671.8 4675.7 4723.3 9556.4 9558.1 9577.0 9584.6 9589.4 5309.9 5319.0 5346.6 5353.0 5355.9 4694.7 4776.5 4849.0 4911.2 4922.5 3891.2 4012.4 4025.1 4087.1 4126.3 1282.0 1284.6 1285.5 1295.2 1299.2 966.1 970.3 972.5 988.1 1005.6 5222.2 5333.7 5386.8 5405.4 5420.6 610.8 615.3 627.9 631.3 641.7 3436.1 3449.7 3452.1 3457.2 3522.5 9329.6 9646.9 9678.6 9693.1 9704.0 9501.6 9598.2 9630.1 9772.5 9787.8 7414.3 7691.0 7713.7 7714.2 7722.8 5374.2 5386.7 5415.2 5447.4 5550.3 8534.9 8649.0 8684.5 8784.3 8839.6 8047.8 8450.2 8973.5 8983.4 9067.1 7062.5 7320.8 7321.2 7322.0 7331.9 7753.9 7842.6 7893.4 7921.6 7947.3 7125.3 7167.8 7205.5 7242.7 7360.4 7100.1 7209.2 7217.2 7223.0 7251.3 6377.5 6378.6 6401.3 6467.7 6472.8 4963.6 5101.3 5140.9 5154.1 5194.3 4997.7 5084.6 5160.3 5240.8 5275.8 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | [#4] prototype.html Cycles:180 Average:4728.42 Median:5415.57 stddev:1345.59 (24.8%) stddev-sans-first:1309.40 17:38:57 INFO - PROCESS | 9789 | Values: 391.8 398.8 398.8 406.5 414.9 5408.7 5415.6 5427.9 5429.3 5433.9 5102.7 5199.4 5269.2 5290.2 5360.4 4223.4 4241.9 4244.7 4245.9 4271.2 4234.3 4237.9 4244.0 4262.4 4274.1 4133.6 4176.8 4185.2 4212.9 4240.8 4194.3 4195.3 4201.0 4211.4 4214.5 3827.4 3844.7 3857.8 3859.7 3872.0 5024.8 5215.2 5245.4 5270.6 5276.2 4855.4 4856.7 4882.0 4911.5 4925.3 5549.0 5554.0 5564.9 5566.9 5590.4 4143.7 4247.3 4255.3 4278.5 4283.8 5716.4 5731.5 5741.2 5797.8 5819.0 4335.9 4354.5 4359.2 4395.1 4429.4 4391.7 4468.8 4473.9 4495.7 4502.6 4896.5 4899.2 4908.8 4916.2 4945.7 5653.5 5662.9 5667.3 5671.9 5701.3 1345.7 1396.7 1499.8 1506.0 1514.2 970.3 974.8 982.2 985.6 985.9 5391.0 5410.3 5414.8 5436.5 5439.8 5415.5 5472.8 5576.4 5578.9 5579.9 5257.9 5371.1 5382.7 5404.2 5495.6 2906.3 2935.5 2956.9 2966.2 2980.8 5879.9 5890.3 5894.3 5902.7 5914.3 5480.9 5662.4 5744.4 5745.8 5757.0 5823.5 5827.9 5831.1 5832.4 5938.3 5328.4 5565.6 5567.3 5579.4 5621.7 5485.8 5506.9 5516.3 5517.7 5518.0 5854.8 5869.5 5871.4 5873.2 5880.5 3929.0 4020.8 4034.4 4047.8 4055.2 5507.3 5531.2 5621.4 5698.2 5721.7 5527.9 5565.8 5574.3 5593.2 5611.0 5419.3 5522.9 5534.6 5540.4 5553.3 5525.7 5531.3 5531.3 5543.2 5553.6 5558.9 5560.9 5603.1 5612.5 5624.9 5205.6 5454.8 5476.3 5499.3 5519.9 17:38:57 INFO - PROCESS | 9789 | 17:38:57 INFO - PROCESS | 9789 | [#5] yui.html Cycles:180 Average:18889.88 Median:44313.78 stddev:24287.81 (54.8%) stddev-sans-first:24318.04 17:38:57 INFO - PROCESS | 9789 | Values: 819.7 833.5 838.6 840.5 846.5 16145.5 16673.2 17071.7 17336.8 17416.8 18153.5 18716.4 18756.2 19003.6 19038.7 806.2 812.8 813.3 813.3 813.5 714.7 725.4 791.0 806.3 815.8 875.9 877.7 878.8 879.3 880.3 875.5 876.0 876.8 877.1 877.5 547.5 553.6 619.2 626.3 627.8 16571.4 16644.5 16665.0 16682.4 16689.7 15354.3 16125.4 16131.8 16201.0 16945.2 13048.5 13115.4 13169.3 13239.1 13355.5 3955.1 3963.5 3978.9 3994.4 4018.9 87741.0 88378.1 88567.6 88817.6 90013.6 11902.2 11927.3 11953.8 12062.2 12222.8 24539.9 24633.6 24719.1 25085.7 25160.5 7991.4 8047.2 8063.5 8106.6 8254.4 4548.8 4623.9 4643.6 4646.8 4663.8 3897.2 4001.8 4002.2 4003.7 4014.3 643.5 650.0 651.1 664.2 678.1 743.1 831.3 836.4 852.6 853.9 10512.3 11444.8 11447.1 11747.7 12011.6 648.8 652.3 655.2 681.3 819.2 5155.9 5521.1 5540.2 5600.0 5659.2 82433.6 85107.6 85395.0 85609.0 87188.0 73039.6 73400.6 74508.6 74724.6 75209.6 64404.0 65659.0 65711.3 66060.0 66764.0 10565.9 10649.8 10666.8 10673.6 10798.6 43991.6 44636.0 44693.8 44832.8 46325.3 55330.4 57350.0 58186.7 58721.4 58728.4 12556.9 12786.8 12911.9 13158.6 13200.2 13834.4 14172.9 14451.0 14812.9 14922.1 13287.9 13473.1 13498.1 13579.3 13788.0 13547.0 13556.3 13695.3 13801.1 13948.9 12704.9 12920.7 12971.5 13100.5 13518.0 11008.3 11136.8 11146.4 11870.0 11871.2 10553.9 10902.2 11081.1 11148.6 11158.9 17:38:57 INFO - PROCESS | 9789 | -------- Summary: end -------- 17:38:57 INFO - PROCESS | 9789 | 17:38:58 INFO - TEST-INFO | 9789: exit 0 17:38:58 INFO - TEST-OK | dromaeo_css | took 1114038ms 17:38:58 INFO - TEST-START | kraken 17:38:58 INFO - operating with platform_type : linux_ 17:38:58 INFO - Initialising browser for kraken test... 17:38:58 INFO - TEST-INFO | started process 10107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCNQa6d/profile http://localhost:33482/getInfo.html) 17:39:02 INFO - PROCESS | 10107 | __metrics Screen width/height:1600/1200 17:39:02 INFO - PROCESS | 10107 | colorDepth:24 17:39:02 INFO - PROCESS | 10107 | Browser inner width/height: 1024/697 17:39:02 INFO - PROCESS | 10107 | __metrics 17:39:03 INFO - PROCESS | 10107 | 1486690743202 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:39:03 INFO - PROCESS | 10107 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:39:03 INFO - PROCESS | 10107 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:39:03 INFO - PROCESS | 10107 | console.error: PushService: 17:39:03 INFO - PROCESS | 10107 | stateChangeProcessEnqueue: Error transitioning state 17:39:03 INFO - PROCESS | 10107 | UnknownError 17:39:04 INFO - TEST-INFO | 10107: exit 0 17:39:04 INFO - Browser initialized. 17:39:04 INFO - Running cycle 1/1 for kraken test... 17:39:04 INFO - TEST-INFO | started process 10199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCNQa6d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:16 INFO - PROCESS | 10199 | RSS: Main: 138321920 17:39:16 INFO - PROCESS | 10199 | 17:39:18 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33482/tests/kraken/driver.html?testName=audio-beat-detection) 17:39:19 INFO - PROCESS | 10199 | RSS: Main: 182161408 17:39:19 INFO - PROCESS | 10199 | 17:39:22 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33482/tests/kraken/driver.html?testName=audio-dft) 17:39:22 INFO - PROCESS | 10199 | RSS: Main: 171446272 17:39:22 INFO - PROCESS | 10199 | 17:39:25 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33482/tests/kraken/driver.html?testName=audio-fft) 17:39:26 INFO - PROCESS | 10199 | RSS: Main: 264138752 17:39:26 INFO - PROCESS | 10199 | 17:39:28 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33482/tests/kraken/driver.html?testName=audio-oscillator) 17:39:28 INFO - PROCESS | 10199 | RSS: Main: 170131456 17:39:28 INFO - PROCESS | 10199 | 17:39:30 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33482/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:39:31 INFO - PROCESS | 10199 | RSS: Main: 172744704 17:39:31 INFO - PROCESS | 10199 | 17:39:34 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33482/tests/kraken/driver.html?testName=imaging-darkroom) 17:39:34 INFO - PROCESS | 10199 | RSS: Main: 211619840 17:39:34 INFO - PROCESS | 10199 | 17:39:38 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33482/tests/kraken/driver.html?testName=imaging-desaturate) 17:39:38 INFO - PROCESS | 10199 | RSS: Main: 210477056 17:39:38 INFO - PROCESS | 10199 | 17:39:41 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33482/tests/kraken/driver.html?testName=json-parse-financial) 17:39:42 INFO - PROCESS | 10199 | RSS: Main: 208195584 17:39:42 INFO - PROCESS | 10199 | 17:39:43 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33482/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:39:44 INFO - PROCESS | 10199 | RSS: Main: 170774528 17:39:44 INFO - PROCESS | 10199 | 17:39:46 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:39:46 INFO - PROCESS | 10199 | RSS: Main: 177393664 17:39:46 INFO - PROCESS | 10199 | 17:39:48 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:39:49 INFO - PROCESS | 10199 | RSS: Main: 186421248 17:39:49 INFO - PROCESS | 10199 | 17:39:51 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:39:52 INFO - PROCESS | 10199 | RSS: Main: 197304320 17:39:52 INFO - PROCESS | 10199 | 17:39:55 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:39:55 INFO - PROCESS | 10199 | RSS: Main: 193028096 17:39:55 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | Cycle 1(1): loaded http://localhost:33482/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33482/tests/kraken/driver.html?testName=ai-astar) 17:39:57 INFO - PROCESS | 10199 | RSS: Main: 244387840 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | __start_tp_report 17:39:57 INFO - PROCESS | 10199 | _x_x_mozilla_page_load 17:39:57 INFO - PROCESS | 10199 | _x_x_mozilla_page_load_details 17:39:57 INFO - PROCESS | 10199 | |i|pagename|runs| 17:39:57 INFO - PROCESS | 10199 | |0;ai-astar;111;112;111;111;112;112;111;113;110;111 17:39:57 INFO - PROCESS | 10199 | |1;audio-beat-detection;142;117;121;162;118;118;120;117;139;117 17:39:57 INFO - PROCESS | 10199 | |2;audio-dft;161;160;161;158;161;159;159;163;160;159 17:39:57 INFO - PROCESS | 10199 | |3;audio-fft;73;75;76;108;75;75;75;75;89;75 17:39:57 INFO - PROCESS | 10199 | |4;audio-oscillator;82;82;103;82;83;83;96;83;83;108 17:39:57 INFO - PROCESS | 10199 | |5;imaging-gaussian-blur;117;117;117;116;116;116;117;117;117;117 17:39:57 INFO - PROCESS | 10199 | |6;imaging-darkroom;146;146;145;146;147;146;146;145;145;146 17:39:57 INFO - PROCESS | 10199 | |7;imaging-desaturate;95;96;95;96;95;95;96;95;95;95 17:39:57 INFO - PROCESS | 10199 | |8;json-parse-financial;49;49;48;49;48;49;50;49;52;54 17:39:57 INFO - PROCESS | 10199 | |9;json-stringify-tinderbox;64;63;63;63;64;64;63;64;68;64 17:39:57 INFO - PROCESS | 10199 | |10;stanford-crypto-aes;77;75;75;76;75;83;83;81;81;74 17:39:57 INFO - PROCESS | 10199 | |11;stanford-crypto-ccm;130;130;127;121;119;118;122;121;171;123 17:39:57 INFO - PROCESS | 10199 | |12;stanford-crypto-pbkdf2;158;158;171;159;157;159;155;163;156;155 17:39:57 INFO - PROCESS | 10199 | |13;stanford-crypto-sha256-iterative;61;64;62;61;61;61;63;60;60;63 17:39:57 INFO - PROCESS | 10199 | __end_tp_report 17:39:57 INFO - PROCESS | 10199 | __start_cc_report 17:39:57 INFO - PROCESS | 10199 | _x_x_mozilla_cycle_collect,1514 17:39:57 INFO - PROCESS | 10199 | __end_cc_report 17:39:57 INFO - PROCESS | 10199 | __startTimestamp1486690797614__endTimestamp 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | ------- Summary: start ------- 17:39:57 INFO - PROCESS | 10199 | Number of tests: 14 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.50 stddev:0.84 (0.8%) stddev-sans-first:0.88 17:39:57 INFO - PROCESS | 10199 | Values: 111.0 112.0 111.0 111.0 112.0 112.0 111.0 113.0 110.0 111.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:120.50 stddev:15.42 (12.8%) stddev-sans-first:15.39 17:39:57 INFO - PROCESS | 10199 | Values: 142.0 117.0 121.0 162.0 118.0 118.0 120.0 117.0 139.0 117.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.45 (0.9%) stddev-sans-first:1.50 17:39:57 INFO - PROCESS | 10199 | Values: 161.0 160.0 161.0 158.0 161.0 159.0 159.0 163.0 160.0 159.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#3] audio-fft Cycles:10 Average:79.60 Median:75.00 stddev:10.95 (14.6%) stddev-sans-first:11.35 17:39:57 INFO - PROCESS | 10199 | Values: 73.0 75.0 76.0 108.0 75.0 75.0 75.0 75.0 89.0 75.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:83.00 stddev:9.97 (12.0%) stddev-sans-first:10.29 17:39:57 INFO - PROCESS | 10199 | Values: 82.0 82.0 103.0 82.0 83.0 83.0 96.0 83.0 83.0 108.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:39:57 INFO - PROCESS | 10199 | Values: 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 17:39:57 INFO - PROCESS | 10199 | Values: 146.0 146.0 145.0 146.0 147.0 146.0 146.0 145.0 145.0 146.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:39:57 INFO - PROCESS | 10199 | Values: 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:49.00 stddev:1.89 (3.9%) stddev-sans-first:1.99 17:39:57 INFO - PROCESS | 10199 | Values: 49.0 49.0 48.0 49.0 48.0 49.0 50.0 49.0 52.0 54.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:1.49 (2.3%) stddev-sans-first:1.58 17:39:57 INFO - PROCESS | 10199 | Values: 64.0 63.0 63.0 63.0 64.0 64.0 63.0 64.0 68.0 64.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:79.00 stddev:3.59 (4.5%) stddev-sans-first:3.79 17:39:57 INFO - PROCESS | 10199 | Values: 77.0 75.0 75.0 76.0 75.0 83.0 83.0 81.0 81.0 74.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:125.00 stddev:15.63 (12.5%) stddev-sans-first:16.56 17:39:57 INFO - PROCESS | 10199 | Values: 130.0 130.0 127.0 121.0 119.0 118.0 122.0 121.0 171.0 123.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.10 Median:158.50 stddev:4.79 (3.0%) stddev-sans-first:5.07 17:39:57 INFO - PROCESS | 10199 | Values: 158.0 158.0 171.0 159.0 157.0 159.0 155.0 163.0 156.0 155.0 17:39:57 INFO - PROCESS | 10199 | 17:39:57 INFO - PROCESS | 10199 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:1.35 (2.2%) stddev-sans-first:1.41 17:39:57 INFO - PROCESS | 10199 | Values: 61.0 64.0 62.0 61.0 61.0 61.0 63.0 60.0 60.0 63.0 17:39:57 INFO - PROCESS | 10199 | -------- Summary: end -------- 17:39:57 INFO - PROCESS | 10199 | 17:39:58 INFO - TEST-INFO | 10199: exit 0 17:39:58 INFO - TEST-OK | kraken | took 60201ms 17:39:58 INFO - SUITE-END | took 1174s 17:39:58 INFO - Completed test suite (00:19:34) 17:39:58 INFO - javascript benchmark 17:39:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.484859846724, 3341.599008119026, 3356.3287342531494, 3365.814880181589, 3393.202597389613, 5372.9462705372935, 5417.404085550591, 5444.945550544493, 5449.209864569635, 5458.4814442627885, 5442.319710036246, 5506.091494903336, 5510.1459273812625, 5527.778888844451, 5553.8333849984465, 4905.141600219974, 4914.901701965959, 4945.876353091166, 4958.082754690394, 5002.324686167375, 4808.158572249859, 4943.307936888838, 4975.378077740283, 4996.625253106014, 5028.296038554606, 4783.28250762385, 4854.101991131652, 4894.36373271476, 4897.902041959159, 4995.325631039799, 4956.553910148086, 4993.525615066563, 4993.800247990094, 4994.55049045584, 5007.774650140753, 4922.26166075088, 4923.113839508923, 4955.0833095877015, 4971.6271279654175, 4977.701337919736, 5238.397584277781, 5323.627346085776, 5338.652987555796, 5384.6769193848495, 5409.404965453797, 5844.211031510734, 5849.356570777211, 5852.356240813507, 5858.355580886099, 5875.9118613220835, 5876.29484461863, 5923.703814809242, 5924.97037514814, 5927.4665280125, 5939.168516407706, 5368.570514358842, 5411.080116380264, 5430.0, 5481.643693159932, 5484.698341591171, 5993.850153746104, 6003.699815009232, 6009.369016253318, 6025.0661147522205, 6047.848803779941, 5790.768369265266, 5794.797182098607, 5794.898969195923, 5799.4780469757925, 5806.1581070744505, 5719.456651618089, 5751.741171647286, 5827.76688932438, 5896.1450589664255, 5898.675572843449, 5692.971535142381, 5728.971355143281, 5761.654300741969, 5782.479576838021, 5813.796517121965, 5802.680852553066, 5803.912941305884, 5809.448102430262, 5811.215485909433, 5813.82558523245, 4417.0724147929295, 4419.359192917009, 4420.84527041552, 4424.690271680952, 4449.7330160190495, 3897.9610203898164, 3923.1172986078473, 3970.940435893406, 3990.2618015667194, 4014.237294913957, 5713.0, 5722.027255366597, 5726.541876649858, 5727.31272247333, 5758.021136406821, 5761.0, 5779.537636989031, 5810.680412577349, 5876.44173803488, 5882.911661342665, 5647.435256474319, 5694.487496125369, 5723.170140329712, 5735.0250457422335, 5748.109043098327, 5028.32117664118, 5036.294918711384, 5041.143005689041, 5042.924356134661, 5050.924236136461, 5984.940150598438, 6029.939700602938, 6176.0, 6183.84540386485, 6183.84540386494, 5791.160281759206, 5849.970750146222, 5850.64896106235, 5918.7928422505, 5958.285005799332, 6184.412480814315, 6190.164327815867, 6198.473129784019, 6205.968970155121, 6208.317085120543, 5600.747966341443, 5759.94240057594, 5827.125931110368, 5835.649861008353, 5837.182794408871, 5768.653880767168, 5774.162746401832, 5797.217375654402, 5798.275215598051, 5922.170896074467, 5752.9712351437975, 5757.769689212385, 5760.135979603093, 5761.107028410603, 5761.135829625589, 5748.597598168088, 5751.626144300607, 5754.4533269339345, 5761.711914404347, 5762.078067509178, 5559.805406810742, 5574.442555744393, 5644.6330988485615, 5655.208270842168, 5678.545716342765, 5769.91345129815, 5781.479666830005, 5784.305883294031, 5784.537237021113, 5784.595078344475, 5615.129654903497, 5636.633618814764, 5656.2929633795775, 5659.915101273402, 5667.1782591524825], "value": 5407.495760827388, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.635743956194, 583.8540364908772, 603.6076550242343, 628.1018144054004, 672.2000819025362, 36288.63711362886, 45940.0, 47115.057698846, 47119.76440117803, 47140.76429617856, 48724.26913596299, 48806.75596622021, 48848.75575622126, 48952.51047489524, 49393.75303123488, 8763.56182190888, 9284.303677224218, 9459.337846350758, 9476.81046379072, 9477.62089516422, 8787.297016238686, 11011.559537618527, 11240.213185077047, 11245.381504017276, 11612.709682257926, 11361.659150225507, 11430.142739294544, 11440.084793216563, 11440.256383335056, 11441.485133168986, 11254.043406310473, 11275.66173014811, 11279.943600282028, 11288.379139147344, 11354.659360219206, 11165.330080195214, 11243.662690119229, 11253.718657033558, 11265.662030139109, 11333.15001374892, 47213.527864721604, 47371.0, 47481.0, 47515.28727069097, 47539.76230118862, 4349.499807522144, 4484.349769283435, 4496.595306422438, 4502.752348620825, 4655.930161047587, 32172.678273217203, 32366.676333236603, 32725.18187045319, 33012.339753204804, 33747.15632109217, 4656.348111264426, 4661.324108004321, 4685.605361250705, 4701.858944231679, 4772.713637181745, 360958.0, 362906.1854690736, 363323.0, 363338.0, 364785.0, 61674.38325616686, 61930.380696193366, 62022.37977620166, 62056.68971655203, 62086.689566551875, 57908.71045644745, 58187.0, 58232.41767582355, 58322.0, 58357.0, 16691.33234670624, 16804.327826886787, 16863.831361686473, 16892.324307027582, 16961.91519042397, 12438.0, 12564.057695672862, 13426.865731342757, 13613.591592252249, 13715.657108572366, 18279.360222392417, 19155.37179339744, 19180.273775360285, 19264.90367548153, 19477.80522194788, 7086.834454025031, 7091.787246382617, 7289.198188199294, 7304.415646748247, 7341.77974660761, 1001.4592120255127, 1006.7634105985088, 1010.1161483701761, 1019.6686077024997, 1031.7575369788094, 33893.66106338905, 33901.66098338985, 34037.14907127292, 34321.0, 34639.65360346415, 1151.9020883225019, 1186.1578279421535, 1187.2994932989577, 1189.8453201083803, 1218.4090716002731, 17234.91382543079, 17913.283468661106, 18141.45575632733, 18213.08934553267, 18228.45314640563, 530593.0, 532171.0, 532669.0, 534709.3264533653, 535065.0, 68636.0, 69271.30728692749, 69324.30675693178, 69440.6527967357, 69626.0, 1499.2803454341977, 1520.5894408509862, 1534.4936171063523, 1543.5909483986752, 1545.4281915691042, 31563.210919726273, 33224.0, 33632.49551256684, 33774.0, 34158.31683366369, 43414.13171736484, 43728.34407483954, 43781.12437751291, 43904.78047609742, 43971.34042989422, 394340.0, 394903.0, 395978.0, 395986.0, 398277.0, 28933.13200603901, 29606.259843504085, 30331.0, 30336.393272134876, 31498.842505787325, 45863.54136458593, 50823.0, 51176.74411627918, 51288.48711512837, 51443.228351574006, 38227.617723822405, 38541.2291754169, 39208.0, 42836.357454638826, 44192.77903610461, 39280.607193927695, 39816.800915995234, 40521.18957620772, 41074.38388424236, 42643.78678106589, 40385.39421908615, 41224.175516488904, 41333.0, 41367.793161034, 41628.79185604052, 34488.82755586206, 36855.63144368522, 37194.0, 37338.8133059333, 37414.438783417725, 27299.454010919275, 27594.724052759215, 27767.583486248128, 27987.440251194454, 28026.159215223575], "value": 21276.893693831113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.38786432486955, 412.92360913231033, 422.16200841329953, 424.89165262857966, 437.1519252649859, 20133.899330503344, 20648.38054858353, 21836.8908155459, 21991.670124948138, 22075.779242207595, 21457.892710536424, 21905.123795048177, 21922.0, 22068.22761203354, 22257.0, 5982.192404025455, 6199.938000620004, 6210.28581713102, 6213.34759850216, 6213.689315534228, 5862.50168735658, 5992.0712289595185, 6008.128821320905, 6016.8495787605225, 6043.546733994946, 5977.222961015062, 5982.162497250389, 5990.311114221877, 6000.75996960121, 6005.12925625784, 5833.212516310286, 5944.346121926585, 5964.7315870785915, 5980.491658209035, 5983.670898100603, 5260.210968354739, 5298.8079417656545, 5335.653182543161, 5351.063563876345, 5353.197020446925, 24587.0, 24596.877015614988, 24843.0, 24871.5025699485, 25060.122895698747, 3190.457622204231, 3232.0, 3279.8688052477873, 3300.372929143455, 3313.105461525399, 15172.089674619558, 15309.234538273042, 15510.61223469411, 15603.297851596706, 15719.449819256386, 1880.2666959885655, 1890.886546807196, 1902.581432084953, 1930.5463216144194, 1957.3834242213657, 68130.0, 70715.2928470719, 71329.64335178342, 91887.0, 93065.06934930632, 9136.862947055799, 10409.323393979366, 11026.44867756609, 11174.10607151426, 11289.137292346672, 16483.175841207893, 16567.088810115558, 16668.83331166697, 16744.66510669804, 16756.16219189036, 8736.257418119403, 8754.606042728092, 8824.117588241124, 9018.503982281038, 9041.412308199946, 8587.914120858837, 8646.43798153118, 8786.297096232285, 8803.075677053945, 8832.337574681796, 7290.869915163158, 7345.325903261262, 7349.926500734924, 7519.135299440525, 7526.209747976493, 1923.422973108062, 1976.8813871167777, 1978.5844972555924, 1994.3418671838258, 2115.915363385478, 957.6887511558771, 973.0804389851522, 975.1467465967279, 980.2599037726471, 990.5146478225618, 20019.899900500695, 20362.796372036388, 20756.79243207579, 20837.68743468849, 21151.365459036253, 973.1922504321396, 977.9266555008261, 978.9657361992439, 981.1415011864618, 990.9355891867006, 11456.140789440826, 11725.589604363806, 11880.940595296968, 12002.879971200176, 12008.63974080779, 648209.0, 657679.0, 676157.0, 676330.0, 676825.0, 86804.56597717098, 89549.0, 90306.54846725856, 91925.5403722977, 92028.0, 1123.5898896902736, 1144.164759725405, 1153.4463457540428, 1155.589765633205, 1160.9482904662666, 17871.73192402141, 17894.91052544729, 18239.452816415527, 18252.36116735908, 18559.35042273514, 42770.0, 43291.35062974121, 43584.564154359316, 43818.12363752771, 43857.56142438535, 56736.43263567312, 57182.7140864293, 57493.71253143708, 57496.12519374098, 57883.0, 7678.424118191047, 8270.007599088149, 8953.865692014495, 8958.641654333753, 9067.455952642862, 13464.528741494392, 13609.659758506117, 13685.86314136846, 13714.925269653177, 14016.439342426189, 11064.557417703523, 11317.773644527228, 11412.828807567726, 11733.941330293294, 12236.510539578318, 10369.325993810378, 11103.111751060058, 11394.715132121763, 11602.535898563963, 11623.88376116228, 11219.943900280447, 11386.202965792316, 12200.75598488043, 12324.08976608627, 12366.44351004189, 8230.958845205736, 8345.83308333842, 8485.490870547786, 8564.400491965753, 8897.91102088971, 10497.2127090467, 11390.943045284721, 11490.48292826808, 12163.81754273669, 12529.874701252871], "value": 9897.259245145999, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.9163510809744, 1482.2070192447043, 1482.4589025005876, 1494.4545240987043, 1497.4609140709345, 7885.802854928623, 8388.0, 8388.119247479019, 8390.160983901607, 8396.91603083969, 7336.889946650791, 7525.322720955113, 7533.171351151369, 7882.842343153134, 8249.422540422172, 2383.9046438142495, 2430.598951173063, 2431.890564924574, 2440.7803297703203, 2440.865752383618, 2400.9039638414442, 2413.75862413759, 2423.5758742220128, 2429.7813196812285, 2434.026389444228, 2347.95304093918, 2364.326167042393, 2372.418757404438, 2378.583747844129, 2391.0674836813655, 2297.0926484038796, 2298.689676893615, 2321.6401457774073, 2326.790588847012, 2327.8486898351553, 2360.114956891166, 2376.6316220985773, 2382.106709983756, 2383.0467812874817, 2386.6300723387903, 8090.797730056737, 8186.713465028695, 8242.835143297161, 8274.710385136552, 8288.21261980111, 5125.948740512622, 5259.395169555473, 5272.077386457355, 5277.577793776489, 5279.392869819943, 3282.2943067240417, 3373.409653310661, 3431.039308993486, 3557.7865328080397, 3559.501669766235, 1436.1885534672876, 1455.185096346039, 1475.0559641829132, 1492.7760835874597, 1500.5123334916086, 10861.022507974241, 10861.67414977552, 10873.347599143997, 10878.456077196108, 10887.945560272226, 4557.475890272594, 4644.094401591725, 4671.8364857229835, 4675.696079754805, 4723.267893476516, 9556.426614403159, 9558.139767420966, 9576.952115239379, 9584.568694408768, 9589.376690515233, 5309.867253318698, 5319.0, 5346.572274218053, 5353.036453438341, 5355.919661205085, 4694.718316900997, 4776.498467660915, 4849.0, 4911.238757992517, 4922.507749225048, 3891.1633998690418, 4012.3580227163516, 4025.054112283611, 4087.0599762054894, 4126.319157339066, 1281.9935900320438, 1284.6338793443824, 1285.5307812648316, 1295.1775622479793, 1299.1620404838825, 966.1353088985459, 970.2722957781664, 972.5088830140734, 988.0761488008735, 1005.6379703306803, 5222.216667499905, 5333.7066461345, 5386.8383948481605, 5405.378381486101, 5420.647657902303, 610.7709608896664, 615.2524682510771, 627.8744251149789, 631.2771876201815, 641.7272659119856, 3436.1409647588102, 3449.6722811332884, 3452.050686061353, 3457.187560923181, 3522.4716292556323, 9329.580168892417, 9646.9035309646, 9678.56446459911, 9693.127618514367, 9703.951480242553, 9501.619935202652, 9598.184154346958, 9630.085141911508, 9772.462514561767, 9787.804243915081, 7414.25857414265, 7691.038620172479, 7713.65288562016, 7714.151443341228, 7722.768316950501, 5374.220737993047, 5386.676799392049, 5415.160650099241, 5447.428020057837, 5550.27846379968, 8534.914650853412, 8648.956755216184, 8684.522351270742, 8784.341174411817, 8839.646414143363, 8047.798805029921, 8450.239478446723, 8973.506457144658, 8983.416077954913, 9067.09329067088, 7062.505624606229, 7320.816979575553, 7321.194668586558, 7322.0, 7331.890021649786, 7753.92246077532, 7842.64708088126, 7893.36853051766, 7921.603919803872, 7947.3244774194845, 7125.287471252833, 7167.820804479929, 7205.495615306878, 7242.710291588278, 7360.411167106511, 7100.147982242164, 7209.206987231505, 7217.170025447141, 7222.96388518054, 7251.274872512707, 6377.4579160771855, 6378.649174295459, 6401.263854656774, 6467.741290348522, 6472.838179045373, 4963.602911766978, 5101.285819985131, 5140.922886156636, 5154.1237989542615, 5194.3247377840635, 4997.700137991732, 5084.618653600921, 5160.277561141368, 5240.816571419982, 5275.788968441219], "value": 4420.305604921895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.8334707749206, 398.8065788092775, 398.8444506642409, 406.48985523168403, 414.88383252689255, 5408.67547947123, 5415.620906536544, 5427.864303392417, 5429.267048948395, 5433.935104660233, 5102.668326558781, 5199.428062913086, 5269.235960785683, 5290.153575427932, 5360.437154098822, 4223.387608796723, 4241.87274381769, 4244.724092933965, 4245.915081698372, 4271.231178387889, 4234.25900467417, 4237.8769626049125, 4243.978780106095, 4262.403263543107, 4274.145170965804, 4133.575447978614, 4176.770277634729, 4185.246655601991, 4212.852550160745, 4240.766757828318, 4194.266003449384, 4195.307774217251, 4201.0, 4211.368294755781, 4214.494260688722, 3827.445020472016, 3844.730868839182, 3857.787821669807, 3859.6912247020173, 3871.9806400968096, 5024.84925452237, 5215.165573508259, 5245.400152953348, 5270.5520030797425, 5276.153346328792, 4855.4023597375235, 4856.708597484162, 4881.974785295092, 4911.50884911506, 4925.310456536088, 5549.0, 5553.972230138864, 5564.944350556483, 5566.916496252519, 5590.413006634326, 4143.7306575072525, 4247.320428731388, 4255.340422234559, 4278.529361770203, 4283.764392958386, 5716.428357164292, 5731.484166425, 5741.224934633813, 5797.826065218051, 5818.970905145489, 4335.869923902288, 4354.521002689701, 4359.215341238544, 4395.099004704028, 4429.424174857312, 4391.692581519263, 4468.843590474317, 4473.888152796206, 4495.662825288116, 4502.581686768723, 4896.46138924718, 4899.191633380452, 4908.803647854046, 4916.164252077156, 4945.703257804544, 5653.491185793298, 5662.924044431545, 5667.319921609434, 5671.914921276184, 5701.344345400332, 1345.6837643153851, 1396.7066915947562, 1499.7926669031535, 1505.9924700376428, 1514.1520748380942, 970.315927271272, 974.7514383832078, 982.2289502740457, 985.5762022330467, 985.8866230383454, 5390.97304513475, 5410.269613602113, 5414.83755487336, 5436.53789427903, 5439.782408703608, 5415.512603865671, 5472.753726082336, 5576.358718747315, 5578.944210557924, 5579.8884022320135, 5257.868553286198, 5371.086915224421, 5382.67703937765, 5404.189371594213, 5495.6153069284765, 2906.346072133795, 2935.486289899302, 2956.8817247309867, 2966.1991262359043, 2980.791344605857, 5879.942020292949, 5890.349342795759, 5894.292684877756, 5902.675352855634, 5914.319853216849, 5480.890382192413, 5662.37713851476, 5744.425557444222, 5745.798897038583, 5756.971215143897, 5823.534117270693, 5827.88344233113, 5831.075745004138, 5832.416758324219, 5938.346781854079, 5328.413874473882, 5565.5825813063375, 5567.331920169444, 5579.386267510489, 5621.690807005491, 5485.84797192578, 5506.944930550803, 5516.255305533701, 5517.69652669107, 5518.0, 5854.765809367577, 5869.4717475427415, 5871.442212989759, 5873.177755114373, 5880.529557635465, 3928.9607103928593, 4020.798960052044, 4034.3948407739076, 4047.838086476626, 4055.1889622075114, 5507.339119305709, 5531.170324451365, 5621.414149491116, 5698.20225168485, 5721.685307308138, 5527.861803454945, 5565.8051968182535, 5574.331080270394, 5593.161025846156, 5610.971945140248, 5419.295491586068, 5522.91715624274, 5534.557235421237, 5540.4209800206745, 5553.278483670075, 5525.696086715108, 5531.280933478622, 5531.308586426697, 5543.223948647274, 5553.6390134641115, 5558.888822223614, 5560.916586251128, 5603.075492543685, 5612.522935550524, 5624.8875022498505, 5205.5835533158015, 5454.809081682122, 5476.315460567429, 5499.312585926759, 5519.889602207853], "value": 4316.243148035859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.692615269274, 833.4707460762418, 838.5807096451774, 840.5293035899897, 846.4963346808648, 16145.515634530968, 16673.166341682914, 17071.74392384112, 17336.826631733693, 17416.82583174168, 18153.547716182708, 18716.43850684478, 18756.155972981214, 19003.61992760144, 19038.714419283722, 806.209914284002, 812.772423721359, 813.308687615528, 813.3168138763438, 813.4996976859219, 714.656964656965, 725.3544345532454, 790.9525428474282, 806.3388021822108, 815.7715839564931, 875.8905136857893, 877.675260153744, 878.8462019146656, 879.2614204068581, 880.3485420788602, 875.4965894610592, 876.0363600039968, 876.7632739160423, 877.1491653096487, 877.5129802959354, 547.5400663442728, 553.6069390732584, 619.201230412767, 626.28603392133, 627.7959663109508, 16571.42000029993, 16644.50066498007, 16665.0, 16682.416115436023, 16689.749653755083, 15354.30905609239, 16125.354985800554, 16131.838681613151, 16201.0, 16945.237464314014, 13048.54330098442, 13115.409806558728, 13169.275689837055, 13239.073264871462, 13355.53255636048, 3955.0903292242947, 3963.4649322341693, 3978.8806335809973, 3994.360902255625, 4018.9397159042637, 87741.0, 88378.11621883763, 88567.55716221378, 88817.55591222068, 90013.64979525378, 11902.226355286879, 11927.284362938251, 11953.820692689616, 12062.215955963009, 12222.816657750142, 24539.87730061338, 24633.63049554258, 24719.134830281215, 25085.749142508706, 25160.496790064095, 7991.360691144694, 8047.155048719917, 8063.516189028677, 8106.594670266463, 8254.380921430797, 4548.840790572314, 4623.8844028898875, 4643.605293550018, 4646.767661616906, 4663.790129444184, 3897.162110146332, 4001.819918103634, 4002.199560087936, 4003.679705623543, 4014.2573623879675, 643.4723526708054, 649.9990015376362, 651.0527182948798, 664.2129077043719, 678.1116737074449, 743.0897150989994, 831.2726364431123, 836.4061516323478, 852.6035393542032, 853.8633818588996, 10512.26414151002, 11444.828327575093, 11447.141464390204, 11747.706307342385, 12011.579594714143, 648.8150877000127, 652.324843786688, 655.1679367203625, 681.3425044831761, 819.2381085590458, 5155.871103222449, 5521.139114963091, 5540.168974653834, 5600.0, 5659.151127330851, 82433.58783206045, 85107.57446212729, 85395.0, 85609.0, 87188.0, 73039.63480182565, 73400.63299683575, 74508.62745686345, 74724.62637686777, 75209.6239518799, 64404.0, 65659.0, 65711.34288657148, 66060.0, 66764.0, 10565.9471702641, 10649.787004260026, 10666.773041865079, 10673.626423075155, 10798.622048228588, 43991.56008439875, 44636.0, 44693.77653111714, 44832.775836120614, 46325.305120423895, 55330.446695532526, 57350.0, 58186.709066454394, 58721.412785873305, 58728.41271587229, 12556.87443125557, 12786.808197877226, 12911.935440322739, 13158.605241842377, 13200.20798752078, 13834.446622135001, 14172.858271417566, 14451.0, 14812.851871481578, 14922.104673719612, 13287.933560332136, 13473.124246923919, 13498.122622029538, 13579.321033948461, 13788.0, 13547.0, 13556.322183890963, 13695.315234238447, 13801.102928309629, 13948.860511394756, 12704.87295127074, 12920.676983075498, 12971.481140754642, 13100.541481048118, 13518.0, 11008.284461509977, 11136.777264454828, 11146.386948717898, 11869.991050760782, 11871.228370155912, 10553.947230263799, 10902.236942877737, 11081.058110060736, 11148.554057837595, 11158.944205278922], "value": 7650.630391128219, "unit": "score"}], "name": "dromaeo_css", "value": 7415.1612358632465, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 111.0, 111.0, 112.0, 112.0, 111.0, 113.0, 110.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 121.0, 162.0, 118.0, 118.0, 120.0, 117.0, 139.0, 117.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 158.0, 161.0, 159.0, 159.0, 163.0, 160.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 108.0, 75.0, 75.0, 75.0, 75.0, 89.0, 75.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 103.0, 82.0, 83.0, 83.0, 96.0, 83.0, 83.0, 108.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 48.0, 49.0, 48.0, 49.0, 50.0, 49.0, 52.0, 54.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 64.0, 68.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 76.0, 75.0, 83.0, 83.0, 81.0, 81.0, 74.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 127.0, 121.0, 119.0, 118.0, 122.0, 121.0, 171.0, 123.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 171.0, 159.0, 157.0, 159.0, 155.0, 163.0, 156.0, 155.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 62.0, 61.0, 61.0, 61.0, 63.0, 60.0, 60.0, 63.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1465.1, "alertThreshold": 2.0}]} 17:39:58 INFO - Return code: 0 17:39:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:39:58 ERROR - # TBPL SUCCESS # 17:39:58 INFO - Running post-action listener: _resource_record_post_action 17:39:58 INFO - [mozharness: 2017-02-10 01:39:58.918346Z] Finished run-tests step (success) 17:39:58 INFO - Running post-run listener: _resource_record_post_run 17:39:58 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 262144; Write bytes: 363851776; Read time: 16; Write time: 264476 17:39:58 INFO - TinderboxPrint: CPU usage
13.0% 17:39:58 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 16 17:39:58 INFO - TinderboxPrint: I/O write bytes / time
363,851,776 / 264,476 17:39:58 INFO - TinderboxPrint: CPU idle
8,266.1 (86.9%) 17:39:58 INFO - TinderboxPrint: CPU user
1,194.2 (12.6%) 17:39:58 INFO - TinderboxPrint: Swap in / out
0 / 0 17:39:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109391872; Read time: 0; Write time: 86868 17:39:59 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 254459904; Read time: 16; Write time: 177608 17:39:59 INFO - Running post-run listener: _upload_blobber_files 17:39:59 INFO - Blob upload gear active. 17:39:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:39:59 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 17:39:59 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:39:59 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:39:59 INFO - (blobuploader) - INFO - Open directory for files ... 17:39:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:39:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:40:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:00 INFO - (blobuploader) - INFO - Done attempting. 17:40:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:40:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:04 INFO - (blobuploader) - INFO - Done attempting. 17:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:05 INFO - (blobuploader) - INFO - Done attempting. 17:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:06 INFO - (blobuploader) - INFO - Done attempting. 17:40:06 INFO - (blobuploader) - INFO - Iteration through files over. 17:40:06 INFO - Return code: 0 17:40:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:40:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:40:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d8830270f8fb4db631bc031f748f1a8df2f426f313bd806a95de036dc8bc674a595bd5463e515429d9b34c05d1bc9a55e1662d413a3cf63df6a1fc88d2adb0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d571fbdeb5b2b5f39c9c381ff6a2d63b14b2b2c3f88ffbaac83fbce338298ffeadc41f58ace59c0712b3a383e72ac4fe73cdf37114eeeba8d55108f767be1fa", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d688063429d0af5c6c6ba4d3480f86dcb8a91cdf66a714b8357590aa8ecc8204cb46d1ecd91f6f02fc94c87b5eaccc806573c054fd2bc45da67f2bbd0a45904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddaf03acc87ebfb77928bdc30efd0bd0d9f2a61ccbaf518f359e59dca0fbf51acd881382f65d011b2d101573051a8b318c406a322fe4cfefdd15b68a44f890e0"} 17:40:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:40:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:40:06 INFO - Contents: 17:40:06 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d8830270f8fb4db631bc031f748f1a8df2f426f313bd806a95de036dc8bc674a595bd5463e515429d9b34c05d1bc9a55e1662d413a3cf63df6a1fc88d2adb0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d571fbdeb5b2b5f39c9c381ff6a2d63b14b2b2c3f88ffbaac83fbce338298ffeadc41f58ace59c0712b3a383e72ac4fe73cdf37114eeeba8d55108f767be1fa", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d688063429d0af5c6c6ba4d3480f86dcb8a91cdf66a714b8357590aa8ecc8204cb46d1ecd91f6f02fc94c87b5eaccc806573c054fd2bc45da67f2bbd0a45904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddaf03acc87ebfb77928bdc30efd0bd0d9f2a61ccbaf518f359e59dca0fbf51acd881382f65d011b2d101573051a8b318c406a322fe4cfefdd15b68a44f890e0"} 17:40:06 INFO - Running post-run listener: copy_logs_to_upload_dir 17:40:06 INFO - Copying logs to upload dir... 17:40:06 INFO - mkdir: /builds/slave/test/build/upload/logs 17:40:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.306937 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 17:40:06.151656) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 17:40:06.155168) ========= 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=9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d8830270f8fb4db631bc031f748f1a8df2f426f313bd806a95de036dc8bc674a595bd5463e515429d9b34c05d1bc9a55e1662d413a3cf63df6a1fc88d2adb0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d571fbdeb5b2b5f39c9c381ff6a2d63b14b2b2c3f88ffbaac83fbce338298ffeadc41f58ace59c0712b3a383e72ac4fe73cdf37114eeeba8d55108f767be1fa", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d688063429d0af5c6c6ba4d3480f86dcb8a91cdf66a714b8357590aa8ecc8204cb46d1ecd91f6f02fc94c87b5eaccc806573c054fd2bc45da67f2bbd0a45904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddaf03acc87ebfb77928bdc30efd0bd0d9f2a61ccbaf518f359e59dca0fbf51acd881382f65d011b2d101573051a8b318c406a322fe4cfefdd15b68a44f890e0"} build_url:https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011384 build_url: 'https://queue.taskcluster.net/v1/task/K2mQcSWIRz6Kq_6qBuDvyw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0d8830270f8fb4db631bc031f748f1a8df2f426f313bd806a95de036dc8bc674a595bd5463e515429d9b34c05d1bc9a55e1662d413a3cf63df6a1fc88d2adb0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d571fbdeb5b2b5f39c9c381ff6a2d63b14b2b2c3f88ffbaac83fbce338298ffeadc41f58ace59c0712b3a383e72ac4fe73cdf37114eeeba8d55108f767be1fa", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d688063429d0af5c6c6ba4d3480f86dcb8a91cdf66a714b8357590aa8ecc8204cb46d1ecd91f6f02fc94c87b5eaccc806573c054fd2bc45da67f2bbd0a45904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddaf03acc87ebfb77928bdc30efd0bd0d9f2a61ccbaf518f359e59dca0fbf51acd881382f65d011b2d101573051a8b318c406a322fe4cfefdd15b68a44f890e0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 17:40:06.184348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:40:06.184781) ========= 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=9bfb7e13875ed353a705960e000001c0-1486689568.239380-681991680 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:40:06.268323) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 17:40:06.268801) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 17:40:06.269214) ========= ========= Total master_lag: 0.16 =========