builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1491843457.98 results: success (0) revision: e774d2cb0fc5b487607d452dc38715acb4040cc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:37.980946) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:37.981372) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:37.981676) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:37.997767) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:37.998512) ========= 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=8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-10 09:57:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2017-04-10 09:57:38 (46.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080252 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.094479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.095227) ========= 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=8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020268 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.144819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.145205) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e774d2cb0fc5b487607d452dc38715acb4040cc7 --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 e774d2cb0fc5b487607d452dc38715acb4040cc7 --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=8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-10 09:57:38,409 truncating revision to first 12 chars 2017-04-10 09:57:38,409 Setting DEBUG logging. 2017-04-10 09:57:38,409 attempt 1/10 2017-04-10 09:57:38,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e774d2cb0fc5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 09:57:38,716 unpacking tar archive at: mozilla-aurora-e774d2cb0fc5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.652978 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.812016) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.812358) ========= script_repo_revision: e774d2cb0fc5b487607d452dc38715acb4040cc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.813102) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.813419) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 09:57:38.823419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-04-10 09:57:38.823805) ========= /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=8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:57:39 INFO - MultiFileLogger online at 20170410 09:57:39 in /builds/slave/test 09:57:39 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 09:57:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:57:39 INFO - {'append_to_log': False, 09:57:39 INFO - 'base_work_dir': '/builds/slave/test', 09:57:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:57:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:57:39 INFO - 'branch': 'Mozilla-Aurora', 09:57:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:57:39 INFO - 'config_files': ('talos/linux_config.py',), 09:57:39 INFO - 'default_actions': ('clobber', 09:57:39 INFO - 'read-buildbot-config', 09:57:39 INFO - 'download-and-extract', 09:57:39 INFO - 'populate-webroot', 09:57:39 INFO - 'create-virtualenv', 09:57:39 INFO - 'install', 09:57:39 INFO - 'run-tests'), 09:57:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:57:39 INFO - 'download_minidump_stackwalk': True, 09:57:39 INFO - 'download_symbols': 'ondemand', 09:57:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:57:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:57:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:57:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:57:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:57:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:57:39 INFO - 'gecko_profile': False, 09:57:39 INFO - 'gecko_profile_interval': 0, 09:57:39 INFO - 'installer_path': 'installer.exe', 09:57:39 INFO - 'log_level': 'info', 09:57:39 INFO - 'log_name': 'talos', 09:57:39 INFO - 'log_to_console': True, 09:57:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:57:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:57:39 INFO - 'opt_config_files': (), 09:57:39 INFO - 'pip_index': False, 09:57:39 INFO - 'suite': 'dromaeojs', 09:57:39 INFO - 'system_bits': '32', 09:57:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:57:39 INFO - 'title': 'talos-linux64-ix-038', 09:57:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:57:39 INFO - 'use_talos_json': True, 09:57:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:57:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:57:39 INFO - 'work_dir': 'build'} 09:57:39 INFO - [mozharness: 2017-04-10 16:57:39.183163Z] Running clobber step. 09:57:39 INFO - Running pre-action listener: _resource_record_pre_action 09:57:39 INFO - Running main action method: clobber 09:57:39 INFO - rmtree: /builds/slave/test/build 09:57:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:57:40 INFO - Running post-action listener: _resource_record_post_action 09:57:40 INFO - [mozharness: 2017-04-10 16:57:40.124233Z] Finished clobber step (success) 09:57:40 INFO - [mozharness: 2017-04-10 16:57:40.124334Z] Running read-buildbot-config step. 09:57:40 INFO - Running pre-action listener: _resource_record_pre_action 09:57:40 INFO - Running main action method: read_buildbot_config 09:57:40 INFO - Using buildbot properties: 09:57:40 INFO - { 09:57:40 INFO - "project": "", 09:57:40 INFO - "product": "firefox", 09:57:40 INFO - "who": "ryanvm@gmail.com", 09:57:40 INFO - "installer_path": "public/build/target.tar.bz2", 09:57:40 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:57:40 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:57:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:57:40 INFO - "stage_platform": "linux64-pgo", 09:57:40 INFO - "basedir": "/builds/slave/test", 09:57:40 INFO - "buildnumber": 878, 09:57:40 INFO - "platform": "ubuntu64_hw", 09:57:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:57:40 INFO - "slavebuilddir": "test", 09:57:40 INFO - "taskId": "bbYuZU0zQReraGcWr4MpkQ", 09:57:40 INFO - "branch": "mozilla-aurora", 09:57:40 INFO - "script_repo_revision": "production", 09:57:40 INFO - "revision": "e774d2cb0fc5b487607d452dc38715acb4040cc7", 09:57:40 INFO - "slavename": "talos-linux64-ix-038", 09:57:40 INFO - "repo_path": "releases/mozilla-aurora" 09:57:40 INFO - } 09:57:40 INFO - Finding installer, test and symbols from parent task. 09:57:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbYuZU0zQReraGcWr4MpkQ'}, attempt #1 09:57:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbYuZU0zQReraGcWr4MpkQ'}, attempt #1 09:57:41 INFO - Task dependencies: FZwn6AZsRuiLxUBTET_wTQ 09:57:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ'}, attempt #1 09:57:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2 09:57:41 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/test_packages.json 09:57:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.crashreporter-symbols.zip 09:57:41 INFO - Running post-action listener: _resource_record_post_action 09:57:41 INFO - [mozharness: 2017-04-10 16:57:41.496884Z] Finished read-buildbot-config step (success) 09:57:41 INFO - [mozharness: 2017-04-10 16:57:41.497100Z] Running download-and-extract step. 09:57:41 INFO - Running pre-action listener: _resource_record_pre_action 09:57:41 INFO - Running main action method: download_and_extract 09:57:41 INFO - mkdir: /builds/slave/test/build/tests 09:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:41 INFO - trying https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.test_packages.json 09:57:41 INFO - Downloading https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:57:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:57:42 INFO - Downloaded 896 bytes. 09:57:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:57:42 INFO - Using the following test package requirements: 09:57:42 INFO - {u'common': [u'target.common.tests.zip'], 09:57:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:57:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:57:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:57:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:57:42 INFO - u'mozbase': [u'target.common.tests.zip'], 09:57:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:57:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:57:42 INFO - u'web-platform': [u'target.common.tests.zip', 09:57:42 INFO - u'target.web-platform.tests.zip'], 09:57:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:57:42 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:57:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.common.tests.zip 09:57:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:57:42 INFO - Fetch https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.common.tests.zip into memory 09:57:43 INFO - Expected file size: 59538774 09:57:43 INFO - Obtained file size: 59538774 09:57:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:57:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.common.tests.zip 09:57:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:57:52 INFO - Fetch https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.common.tests.zip into memory 09:57:54 INFO - Expected file size: 59538774 09:57:54 INFO - Obtained file size: 59538774 09:58:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.talos.tests.zip 09:58:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:58:04 INFO - Fetch https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.talos.tests.zip into memory 09:58:05 INFO - Expected file size: 14404424 09:58:05 INFO - Obtained file size: 14404424 09:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:05 INFO - trying https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2 09:58:05 INFO - Downloading https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:58:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:58:06 INFO - Downloaded 66645666 bytes. 09:58:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2 09:58:06 INFO - mkdir: /builds/slave/test/properties 09:58:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:06 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:06 INFO - Contents: 09:58:06 INFO - build_url:https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2 09:58:06 INFO - Running post-action listener: _resource_record_post_action 09:58:06 INFO - Running post-action listener: set_extra_try_arguments 09:58:06 INFO - [mozharness: 2017-04-10 16:58:06.881247Z] Finished download-and-extract step (success) 09:58:06 INFO - [mozharness: 2017-04-10 16:58:06.881344Z] Running populate-webroot step. 09:58:06 INFO - Running pre-action listener: _resource_record_pre_action 09:58:06 INFO - Running main action method: populate_webroot 09:58:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:06 INFO - '--tppagecycles', 09:58:06 INFO - '7'], 09:58:06 INFO - 'tests': ['tsvgm']}}, 09:58:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['damp', 'tps']}, 09:58:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'tests': ['damp', 'tps']}, 09:58:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['dromaeo_dom']}, 09:58:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['basic_compositor_video']}, 09:58:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:58:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['a11yr', 09:58:06 INFO - 'ts_paint', 09:58:06 INFO - 'tpaint', 09:58:06 INFO - 'sessionrestore', 09:58:06 INFO - 'sessionrestore_no_auto_restore', 09:58:06 INFO - 'tabpaint']}, 09:58:06 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:06 INFO - 'ts_paint', 09:58:06 INFO - 'tpaint', 09:58:06 INFO - 'sessionrestore', 09:58:06 INFO - 'sessionrestore_no_auto_restore', 09:58:06 INFO - 'tabpaint']}, 09:58:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:06 INFO - 'ts_paint', 09:58:06 INFO - 'tpaint', 09:58:06 INFO - 'sessionrestore', 09:58:06 INFO - 'sessionrestore_no_auto_restore', 09:58:06 INFO - 'tabpaint']}, 09:58:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:06 INFO - 'ts_paint', 09:58:06 INFO - 'tpaint', 09:58:06 INFO - 'sessionrestore', 09:58:06 INFO - 'sessionrestore_no_auto_restore', 09:58:06 INFO - 'tabpaint']}, 09:58:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['a11yr', 09:58:06 INFO - 'ts_paint', 09:58:06 INFO - 'tpaint', 09:58:06 INFO - 'sessionrestore', 09:58:06 INFO - 'sessionrestore_no_auto_restore', 09:58:06 INFO - 'tabpaint']}, 09:58:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['a11yr', 09:58:06 INFO - 'ts_paint', 09:58:06 INFO - 'tpaint', 09:58:06 INFO - 'sessionrestore', 09:58:06 INFO - 'sessionrestore_no_auto_restore', 09:58:06 INFO - 'tabpaint']}, 09:58:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['tsvgx', 09:58:06 INFO - 'tsvgr_opacity', 09:58:06 INFO - 'tart', 09:58:06 INFO - 'tscrollx', 09:58:06 INFO - 'cart', 09:58:06 INFO - 'tsvg_static']}, 09:58:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:06 INFO - 'tsvgr_opacity', 09:58:06 INFO - 'tart', 09:58:06 INFO - 'tscrollx', 09:58:06 INFO - 'cart', 09:58:06 INFO - 'tsvg_static']}, 09:58:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'talos_options': ['--disable-e10s'], 09:58:06 INFO - 'tests': ['tp5o']}, 09:58:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:58:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'talos_options': ['--disable-e10s', 09:58:06 INFO - '--xperf_path', 09:58:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:06 INFO - 'tests': ['tp5n']}, 09:58:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:58:06 INFO - 'talos_options': ['--xperf_path', 09:58:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:06 INFO - 'tests': ['tp5n']}}, 09:58:06 INFO - 'talos.zip': {'path': '', 09:58:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:06 INFO - Running post-action listener: _resource_record_post_action 09:58:06 INFO - [mozharness: 2017-04-10 16:58:06.894458Z] Finished populate-webroot step (success) 09:58:06 INFO - [mozharness: 2017-04-10 16:58:06.894558Z] Running create-virtualenv step. 09:58:06 INFO - Running pre-action listener: _resource_record_pre_action 09:58:06 INFO - Running main action method: create_virtualenv 09:58:06 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:06 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 09:58:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:06 INFO - Using real prefix '/usr' 09:58:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:07 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:09 INFO - Installing pip.................done. 09:58:09 INFO - Return code: 0 09:58:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:58:09 INFO - Reading from file tmpfile_stdout 09:58:09 INFO - Output received: 09:58:09 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) 09:58:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58:09 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')]} 09:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:09 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')]} 09:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:09 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 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:09 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 09:58:09 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 09:58:09 INFO - Using env: {'DISPLAY': ':0', 09:58:09 INFO - 'HOME': '/home/cltbld', 09:58:09 INFO - 'LANG': 'en_US.UTF-8', 09:58:09 INFO - 'LANGUAGE': 'en_US:en', 09:58:09 INFO - 'LOGNAME': 'cltbld', 09:58:09 INFO - 'MAIL': '/var/mail/cltbld', 09:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:09 INFO - 'MOZ_NO_REMOTE': '1', 09:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:09 INFO - 'NO_EM_RESTART': '1', 09:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:09 INFO - 'PWD': '/builds/slave/test', 09:58:09 INFO - 'SHELL': '/bin/bash', 09:58:09 INFO - 'SHLVL': '1', 09:58:09 INFO - 'TERM': 'linux', 09:58:09 INFO - 'TMOUT': '86400', 09:58:09 INFO - 'USER': 'cltbld', 09:58:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:09 INFO - '_': '/tools/buildbot/bin/python'} 09:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:09 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 09:58:09 INFO - Cleaning up... 09:58:09 INFO - Return code: 0 09:58:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:58:09 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')]} 09:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:09 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')]} 09:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:09 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 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:09 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 09:58:09 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 09:58:09 INFO - Using env: {'DISPLAY': ':0', 09:58:09 INFO - 'HOME': '/home/cltbld', 09:58:09 INFO - 'LANG': 'en_US.UTF-8', 09:58:09 INFO - 'LANGUAGE': 'en_US:en', 09:58:09 INFO - 'LOGNAME': 'cltbld', 09:58:09 INFO - 'MAIL': '/var/mail/cltbld', 09:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:09 INFO - 'MOZ_NO_REMOTE': '1', 09:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:09 INFO - 'NO_EM_RESTART': '1', 09:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:09 INFO - 'PWD': '/builds/slave/test', 09:58:09 INFO - 'SHELL': '/bin/bash', 09:58:09 INFO - 'SHLVL': '1', 09:58:09 INFO - 'TERM': 'linux', 09:58:09 INFO - 'TMOUT': '86400', 09:58:09 INFO - 'USER': 'cltbld', 09:58:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:09 INFO - '_': '/tools/buildbot/bin/python'} 09:58:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:10 INFO - Downloading/unpacking psutil>=3.1.1 09:58:10 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 09:58:10 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 09:58:10 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 09:58:10 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 09:58:10 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 09:58:10 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 09:58:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:12 INFO - Installing collected packages: psutil 09:58:12 INFO - Running setup.py install for psutil 09:58:12 INFO - building 'psutil._psutil_linux' extension 09:58:12 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 09:58:12 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 09:58:12 INFO - building 'psutil._psutil_posix' extension 09:58:12 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 09:58:12 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 09:58:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:12 INFO - Successfully installed psutil 09:58:12 INFO - Cleaning up... 09:58:13 INFO - Return code: 0 09:58:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:13 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 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:13 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 09:58:13 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 09:58:13 INFO - Using env: {'DISPLAY': ':0', 09:58:13 INFO - 'HOME': '/home/cltbld', 09:58:13 INFO - 'LANG': 'en_US.UTF-8', 09:58:13 INFO - 'LANGUAGE': 'en_US:en', 09:58:13 INFO - 'LOGNAME': 'cltbld', 09:58:13 INFO - 'MAIL': '/var/mail/cltbld', 09:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:13 INFO - 'MOZ_NO_REMOTE': '1', 09:58:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:13 INFO - 'NO_EM_RESTART': '1', 09:58:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:13 INFO - 'PWD': '/builds/slave/test', 09:58:13 INFO - 'SHELL': '/bin/bash', 09:58:13 INFO - 'SHLVL': '1', 09:58:13 INFO - 'TERM': 'linux', 09:58:13 INFO - 'TMOUT': '86400', 09:58:13 INFO - 'USER': 'cltbld', 09:58:13 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:13 INFO - '_': '/tools/buildbot/bin/python'} 09:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:58:13 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 09:58:13 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 09:58:13 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 09:58:13 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 09:58:13 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 09:58:13 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 09:58:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:58:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:58:15 INFO - Installing collected packages: mozsystemmonitor 09:58:15 INFO - Running setup.py install for mozsystemmonitor 09:58:15 INFO - Successfully installed mozsystemmonitor 09:58:15 INFO - Cleaning up... 09:58:15 INFO - Return code: 0 09:58:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:58:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:58:15 INFO - Using env: {'DISPLAY': ':0', 09:58:15 INFO - 'HOME': '/home/cltbld', 09:58:15 INFO - 'LANG': 'en_US.UTF-8', 09:58:15 INFO - 'LANGUAGE': 'en_US:en', 09:58:15 INFO - 'LOGNAME': 'cltbld', 09:58:15 INFO - 'MAIL': '/var/mail/cltbld', 09:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:15 INFO - 'MOZ_NO_REMOTE': '1', 09:58:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:15 INFO - 'NO_EM_RESTART': '1', 09:58:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:15 INFO - 'PWD': '/builds/slave/test', 09:58:15 INFO - 'SHELL': '/bin/bash', 09:58:15 INFO - 'SHLVL': '1', 09:58:15 INFO - 'TERM': 'linux', 09:58:15 INFO - 'TMOUT': '86400', 09:58:15 INFO - 'USER': 'cltbld', 09:58:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:15 INFO - '_': '/tools/buildbot/bin/python'} 09:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:18 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:18 INFO - warning: no files found matching '*.txt' 09:58:18 INFO - no previously-included directories found matching 'build' 09:58:18 INFO - no previously-included directories found matching 'dist' 09:58:18 INFO - no previously-included directories found matching '.git*' 09:58:18 INFO - Installing collected packages: jsonschema, functools32 09:58:18 INFO - Running setup.py install for functools32 09:58:18 INFO - warning: no files found matching '*.txt' 09:58:18 INFO - no previously-included directories found matching 'build' 09:58:18 INFO - no previously-included directories found matching 'dist' 09:58:18 INFO - no previously-included directories found matching '.git*' 09:58:18 INFO - Successfully installed jsonschema functools32 09:58:18 INFO - Cleaning up... 09:58:18 INFO - Return code: 0 09:58:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:58:18 INFO - Using env: {'DISPLAY': ':0', 09:58:18 INFO - 'HOME': '/home/cltbld', 09:58:18 INFO - 'LANG': 'en_US.UTF-8', 09:58:18 INFO - 'LANGUAGE': 'en_US:en', 09:58:18 INFO - 'LOGNAME': 'cltbld', 09:58:18 INFO - 'MAIL': '/var/mail/cltbld', 09:58:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:18 INFO - 'MOZ_NO_REMOTE': '1', 09:58:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:18 INFO - 'NO_EM_RESTART': '1', 09:58:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:18 INFO - 'PWD': '/builds/slave/test', 09:58:18 INFO - 'SHELL': '/bin/bash', 09:58:18 INFO - 'SHLVL': '1', 09:58:18 INFO - 'TERM': 'linux', 09:58:18 INFO - 'TMOUT': '86400', 09:58:18 INFO - 'USER': 'cltbld', 09:58:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:18 INFO - '_': '/tools/buildbot/bin/python'} 09:58:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:58:19 INFO - Cleaning up... 09:58:19 INFO - Return code: 0 09:58:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:58:19 INFO - Using env: {'DISPLAY': ':0', 09:58:19 INFO - 'HOME': '/home/cltbld', 09:58:19 INFO - 'LANG': 'en_US.UTF-8', 09:58:19 INFO - 'LANGUAGE': 'en_US:en', 09:58:19 INFO - 'LOGNAME': 'cltbld', 09:58:19 INFO - 'MAIL': '/var/mail/cltbld', 09:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:19 INFO - 'MOZ_NO_REMOTE': '1', 09:58:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:19 INFO - 'NO_EM_RESTART': '1', 09:58:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:19 INFO - 'PWD': '/builds/slave/test', 09:58:19 INFO - 'SHELL': '/bin/bash', 09:58:19 INFO - 'SHLVL': '1', 09:58:19 INFO - 'TERM': 'linux', 09:58:19 INFO - 'TMOUT': '86400', 09:58:19 INFO - 'USER': 'cltbld', 09:58:19 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:19 INFO - '_': '/tools/buildbot/bin/python'} 09:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:19 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:21 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:58:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:22 INFO - Downloading docopt-0.6.1.tar.gz 09:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:22 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:22 INFO - Running setup.py install for blobuploader 09:58:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:58:22 INFO - Running setup.py install for requests 09:58:22 INFO - Running setup.py install for docopt 09:58:22 INFO - Successfully installed blobuploader requests docopt 09:58:22 INFO - Cleaning up... 09:58:22 INFO - Return code: 0 09:58:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:22 INFO - Using env: {'DISPLAY': ':0', 09:58:22 INFO - 'HOME': '/home/cltbld', 09:58:22 INFO - 'LANG': 'en_US.UTF-8', 09:58:22 INFO - 'LANGUAGE': 'en_US:en', 09:58:22 INFO - 'LOGNAME': 'cltbld', 09:58:22 INFO - 'MAIL': '/var/mail/cltbld', 09:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:22 INFO - 'MOZ_NO_REMOTE': '1', 09:58:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:22 INFO - 'NO_EM_RESTART': '1', 09:58:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:22 INFO - 'PWD': '/builds/slave/test', 09:58:22 INFO - 'SHELL': '/bin/bash', 09:58:22 INFO - 'SHLVL': '1', 09:58:22 INFO - 'TERM': 'linux', 09:58:22 INFO - 'TMOUT': '86400', 09:58:22 INFO - 'USER': 'cltbld', 09:58:22 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:22 INFO - '_': '/tools/buildbot/bin/python'} 09:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:23 INFO - Running setup.py (path:/tmp/pip-gttTLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:23 INFO - Running setup.py (path:/tmp/pip-BEqfwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:23 INFO - Running setup.py (path:/tmp/pip-Hj8LTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:23 INFO - Running setup.py (path:/tmp/pip-wN_FYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:23 INFO - Running setup.py (path:/tmp/pip-wnDR05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:23 INFO - Running setup.py (path:/tmp/pip-rotNCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:23 INFO - Running setup.py (path:/tmp/pip-_D252g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:23 INFO - Running setup.py (path:/tmp/pip-oCPdrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:23 INFO - Running setup.py (path:/tmp/pip-FBvjeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:23 INFO - Running setup.py (path:/tmp/pip-5kURy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:23 INFO - Running setup.py (path:/tmp/pip-83AO63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:23 INFO - Running setup.py (path:/tmp/pip-RXtPfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:23 INFO - Running setup.py (path:/tmp/pip-44WJs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:24 INFO - Running setup.py (path:/tmp/pip-IDgfEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:24 INFO - Running setup.py (path:/tmp/pip-iY1DXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:24 INFO - Running setup.py (path:/tmp/pip-3SecVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:24 INFO - Running setup.py (path:/tmp/pip-xeX15c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:24 INFO - Running setup.py install for manifestparser 09:58:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:24 INFO - Running setup.py install for mozcrash 09:58:24 INFO - Running setup.py install for mozdebug 09:58:24 INFO - Running setup.py install for mozdevice 09:58:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:24 INFO - Running setup.py install for mozfile 09:58:24 INFO - Running setup.py install for mozhttpd 09:58:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:24 INFO - Running setup.py install for mozinfo 09:58:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Running setup.py install for mozInstall 09:58:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Running setup.py install for mozleak 09:58:25 INFO - Running setup.py install for mozlog 09:58:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Running setup.py install for moznetwork 09:58:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Running setup.py install for mozprocess 09:58:25 INFO - Running setup.py install for mozprofile 09:58:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Running setup.py install for mozrunner 09:58:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:25 INFO - Running setup.py install for mozscreenshot 09:58:25 INFO - Running setup.py install for moztest 09:58:25 INFO - Running setup.py install for mozversion 09:58:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:26 INFO - Cleaning up... 09:58:26 INFO - Return code: 0 09:58:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:26 INFO - Using env: {'DISPLAY': ':0', 09:58:26 INFO - 'HOME': '/home/cltbld', 09:58:26 INFO - 'LANG': 'en_US.UTF-8', 09:58:26 INFO - 'LANGUAGE': 'en_US:en', 09:58:26 INFO - 'LOGNAME': 'cltbld', 09:58:26 INFO - 'MAIL': '/var/mail/cltbld', 09:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:26 INFO - 'MOZ_NO_REMOTE': '1', 09:58:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:26 INFO - 'NO_EM_RESTART': '1', 09:58:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:26 INFO - 'PWD': '/builds/slave/test', 09:58:26 INFO - 'SHELL': '/bin/bash', 09:58:26 INFO - 'SHLVL': '1', 09:58:26 INFO - 'TERM': 'linux', 09:58:26 INFO - 'TMOUT': '86400', 09:58:26 INFO - 'USER': 'cltbld', 09:58:26 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:26 INFO - '_': '/tools/buildbot/bin/python'} 09:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:26 INFO - Running setup.py (path:/tmp/pip-wsCSwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:26 INFO - Running setup.py (path:/tmp/pip-4ILefp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:26 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)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:26 INFO - Running setup.py (path:/tmp/pip-tM9wuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:26 INFO - Running setup.py (path:/tmp/pip-9Cuagv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:26 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)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:26 INFO - Running setup.py (path:/tmp/pip-Oa5MN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:26 INFO - Running setup.py (path:/tmp/pip-0jknC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:26 INFO - Running setup.py (path:/tmp/pip-Wd6Wm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:26 INFO - Running setup.py (path:/tmp/pip-MJe0b2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:26 INFO - Running setup.py (path:/tmp/pip-qcNzTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:27 INFO - Running setup.py (path:/tmp/pip-AtkJFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:27 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)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:27 INFO - Running setup.py (path:/tmp/pip-QVOojZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:27 INFO - Running setup.py (path:/tmp/pip-any9AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:27 INFO - Running setup.py (path:/tmp/pip-nsv6O7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:27 INFO - Running setup.py (path:/tmp/pip-9eSbsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:27 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)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:27 INFO - Running setup.py (path:/tmp/pip-CQ89xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:27 INFO - Running setup.py (path:/tmp/pip-c9fryr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:27 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)) 09:58:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:27 INFO - Running setup.py (path:/tmp/pip-qTz7EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:29 INFO - Downloading blessings-1.6.tar.gz 09:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:29 INFO - Installing collected packages: blessings 09:58:29 INFO - Running setup.py install for blessings 09:58:29 INFO - Successfully installed blessings 09:58:29 INFO - Cleaning up... 09:58:29 INFO - Return code: 0 09:58:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:30 INFO - Reading from file tmpfile_stdout 09:58:30 INFO - Current package versions: 09:58:30 INFO - argparse == 1.2.1 09:58:30 INFO - blessings == 1.6 09:58:30 INFO - blobuploader == 1.2.4 09:58:30 INFO - docopt == 0.6.1 09:58:30 INFO - functools32 == 3.2.3-2 09:58:30 INFO - jsonschema == 2.5.1 09:58:30 INFO - manifestparser == 1.1 09:58:30 INFO - mozInstall == 1.12 09:58:30 INFO - mozcrash == 1.0 09:58:30 INFO - mozdebug == 0.1 09:58:30 INFO - mozdevice == 0.49 09:58:30 INFO - mozfile == 1.2 09:58:30 INFO - mozhttpd == 0.7 09:58:30 INFO - mozinfo == 0.9 09:58:30 INFO - mozleak == 0.1 09:58:30 INFO - mozlog == 3.4 09:58:30 INFO - moznetwork == 0.27 09:58:30 INFO - mozprocess == 0.25 09:58:30 INFO - mozprofile == 0.28 09:58:30 INFO - mozrunner == 6.13 09:58:30 INFO - mozscreenshot == 0.1 09:58:30 INFO - mozsystemmonitor == 0.3 09:58:30 INFO - moztest == 0.8 09:58:30 INFO - mozversion == 1.4 09:58:30 INFO - psutil == 3.1.1 09:58:30 INFO - requests == 1.2.3 09:58:30 INFO - wsgiref == 0.1.2 09:58:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 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 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:30 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 09:58:30 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 09:58:30 INFO - Using env: {'DISPLAY': ':0', 09:58:30 INFO - 'HOME': '/home/cltbld', 09:58:30 INFO - 'LANG': 'en_US.UTF-8', 09:58:30 INFO - 'LANGUAGE': 'en_US:en', 09:58:30 INFO - 'LOGNAME': 'cltbld', 09:58:30 INFO - 'MAIL': '/var/mail/cltbld', 09:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:30 INFO - 'MOZ_NO_REMOTE': '1', 09:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:30 INFO - 'NO_EM_RESTART': '1', 09:58:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:30 INFO - 'PWD': '/builds/slave/test', 09:58:30 INFO - 'SHELL': '/bin/bash', 09:58:30 INFO - 'SHLVL': '1', 09:58:30 INFO - 'TERM': 'linux', 09:58:30 INFO - 'TMOUT': '86400', 09:58:30 INFO - 'USER': 'cltbld', 09:58:30 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:30 INFO - '_': '/tools/buildbot/bin/python'} 09:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:30 INFO - Cleaning up... 09:58:30 INFO - Return code: 0 09:58:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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 0x10e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x137fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, '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': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', '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 09:58:35 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 09:58:35 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 09:58:35 INFO - Using env: {'DISPLAY': ':0', 09:58:35 INFO - 'HOME': '/home/cltbld', 09:58:35 INFO - 'LANG': 'en_US.UTF-8', 09:58:35 INFO - 'LANGUAGE': 'en_US:en', 09:58:35 INFO - 'LOGNAME': 'cltbld', 09:58:35 INFO - 'MAIL': '/var/mail/cltbld', 09:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:35 INFO - 'MOZ_NO_REMOTE': '1', 09:58:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:35 INFO - 'NO_EM_RESTART': '1', 09:58:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:35 INFO - 'PWD': '/builds/slave/test', 09:58:35 INFO - 'SHELL': '/bin/bash', 09:58:35 INFO - 'SHLVL': '1', 09:58:35 INFO - 'TERM': 'linux', 09:58:35 INFO - 'TMOUT': '86400', 09:58:35 INFO - 'USER': 'cltbld', 09:58:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:35 INFO - '_': '/tools/buildbot/bin/python'} 09:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:35 INFO - Cleaning up... 09:58:35 INFO - Return code: 0 09:58:35 INFO - Running post-action listener: _resource_record_post_action 09:58:35 INFO - Running post-action listener: _start_resource_monitoring 09:58:35 INFO - Starting resource monitoring. 09:58:35 INFO - [mozharness: 2017-04-10 16:58:35.557522Z] Finished create-virtualenv step (success) 09:58:35 INFO - [mozharness: 2017-04-10 16:58:35.557983Z] Running install step. 09:58:35 INFO - Running pre-action listener: _resource_record_pre_action 09:58:35 INFO - Running main action method: install 09:58:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:35 INFO - Reading from file tmpfile_stdout 09:58:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:35 INFO - Reading from file tmpfile_stdout 09:58:35 INFO - Output received: 09:58:35 INFO - Usage: mozinstall [options] installer 09:58:35 INFO - Options: 09:58:35 INFO - -h, --help show this help message and exit 09:58:35 INFO - -d DEST, --destination=DEST 09:58:35 INFO - Directory to install application into. [default: 09:58:35 INFO - "/builds/slave/test"] 09:58:35 INFO - --app=APP Application being installed. [default: firefox] 09:58:35 INFO - mkdir: /builds/slave/test/build/application 09:58:35 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'] 09:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:58:52 INFO - Reading from file tmpfile_stdout 09:58:52 INFO - Output received: 09:58:52 INFO - /builds/slave/test/build/application/firefox/firefox 09:58:52 INFO - Running post-action listener: _resource_record_post_action 09:58:52 INFO - [mozharness: 2017-04-10 16:58:52.426069Z] Finished install step (success) 09:58:52 INFO - [mozharness: 2017-04-10 16:58:52.426217Z] Running run-tests step. 09:58:52 INFO - Running pre-action listener: _resource_record_pre_action 09:58:52 INFO - Running main action method: run_tests 09:58:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:58:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:58:52 INFO - Python 2.7.3 09:58:52 INFO - Return code: 0 09:58:52 INFO - grabbing minidump binary from tooltool 09:58:52 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')]} 09:58:52 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 0x137d290>, '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 0x137db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137e040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:58:52 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 09:58:52 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 09:58:52 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 09:58:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:52 INFO - Return code: 0 09:58:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:58:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:58:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:58:52 INFO - ENV: RUST_BACKTRACE is now 1 09:58:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:58:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:58:52 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/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 09:58:52 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/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 09:58:52 INFO - Using env: {'DISPLAY': ':0', 09:58:52 INFO - 'HOME': '/home/cltbld', 09:58:52 INFO - 'LANG': 'en_US.UTF-8', 09:58:52 INFO - 'LANGUAGE': 'en_US:en', 09:58:52 INFO - 'LOGNAME': 'cltbld', 09:58:52 INFO - 'MAIL': '/var/mail/cltbld', 09:58:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:58:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:52 INFO - 'MOZ_NO_REMOTE': '1', 09:58:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:58:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:52 INFO - 'NO_EM_RESTART': '1', 09:58:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:52 INFO - 'PWD': '/builds/slave/test', 09:58:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:58:52 INFO - 'RUST_BACKTRACE': '1', 09:58:52 INFO - 'SHELL': '/bin/bash', 09:58:52 INFO - 'SHLVL': '1', 09:58:52 INFO - 'TERM': 'linux', 09:58:52 INFO - 'TMOUT': '86400', 09:58:52 INFO - 'USER': 'cltbld', 09:58:52 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312', 09:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:52 INFO - '_': '/tools/buildbot/bin/python'} 09:58:52 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/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 09:58:52 INFO - mozversion application_buildid: 20170410160428 09:58:52 INFO - mozversion application_changeset: e774d2cb0fc5b487607d452dc38715acb4040cc7 09:58:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:58:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:52 INFO - mozversion application_name: Firefox 09:58:52 INFO - mozversion application_remotingname: firefox-dev 09:58:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:58:52 INFO - mozversion application_vendor: Mozilla 09:58:52 INFO - mozversion application_version: 54.0a2 09:58:52 INFO - mozversion platform_buildid: 20170410160428 09:58:52 INFO - mozversion platform_changeset: e774d2cb0fc5b487607d452dc38715acb4040cc7 09:58:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:58:52 INFO - mozversion platform_version: 54.0a2 09:58:52 INFO - using testdate: 1491843532 09:58:52 INFO - actual date: 1491843532 09:58:52 INFO - starting webserver on 'localhost:35159' 09:58:52 INFO - SUITE-START | Running 2 tests 09:58:52 INFO - TEST-START | dromaeo_css 09:58:52 INFO - operating with platform_type : linux_ 09:58:52 INFO - Initialising browser for dromaeo_css test... 09:58:52 INFO - TEST-INFO | started process 27394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSFv879/profile http://localhost:35159/getInfo.html) 09:58:57 INFO - PROCESS | 27394 | __metrics Screen width/height:1600/1200 09:58:57 INFO - PROCESS | 27394 | colorDepth:24 09:58:57 INFO - PROCESS | 27394 | Browser inner width/height: 1024/697 09:58:57 INFO - PROCESS | 27394 | __metrics 09:58:58 INFO - PROCESS | 27394 | 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" 09:58:58 INFO - PROCESS | 27394 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:59 INFO - TEST-INFO | 27394: exit 0 09:58:59 INFO - Browser initialized. 09:58:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:58:59 INFO - TEST-INFO | started process 27484 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSFv879/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:11 INFO - PROCESS | 27484 | RSS: Main: 139956224 09:59:11 INFO - PROCESS | 27484 | 10:02:12 INFO - PROCESS | 27484 | Cycle 1(1): loaded http://localhost:35159/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35159/tests/dromaeo/cssquery-ext.html) 10:02:14 INFO - PROCESS | 27484 | RSS: Main: 279932928 10:02:14 INFO - PROCESS | 27484 | 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | A coding exception was thrown and uncaught in a Task. 10:04:00 INFO - PROCESS | 27484 | 10:04:00 ERROR - PROCESS | 27484 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:04:00 INFO - PROCESS | 27484 | Full stack: 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | A coding exception was thrown and uncaught in a Task. 10:04:00 INFO - PROCESS | 27484 | 10:04:00 ERROR - PROCESS | 27484 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:04:00 INFO - PROCESS | 27484 | Full stack: 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | A coding exception was thrown and uncaught in a Task. 10:04:00 INFO - PROCESS | 27484 | 10:04:00 ERROR - PROCESS | 27484 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:04:00 INFO - PROCESS | 27484 | Full stack: 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | ************************* 10:04:00 INFO - PROCESS | 27484 | A coding exception was thrown and uncaught in a Task. 10:04:00 INFO - PROCESS | 27484 | 10:04:00 ERROR - PROCESS | 27484 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:04:00 INFO - PROCESS | 27484 | Full stack: 10:04:00 INFO - PROCESS | 27484 | ************************* 10:05:16 INFO - PROCESS | 27484 | Cycle 1(1): loaded http://localhost:35159/tests/dromaeo/cssquery-ext.html (next: http://localhost:35159/tests/dromaeo/cssquery-jquery.html) 10:05:16 INFO - PROCESS | 27484 | RSS: Main: 218456064 10:05:16 INFO - PROCESS | 27484 | 10:08:18 INFO - PROCESS | 27484 | Cycle 1(1): loaded http://localhost:35159/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35159/tests/dromaeo/cssquery-mootools.html) 10:08:19 INFO - PROCESS | 27484 | RSS: Main: 169447424 10:08:19 INFO - PROCESS | 27484 | 10:11:21 INFO - PROCESS | 27484 | Cycle 1(1): loaded http://localhost:35159/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35159/tests/dromaeo/cssquery-prototype.html) 10:11:21 INFO - PROCESS | 27484 | RSS: Main: 199385088 10:11:21 INFO - PROCESS | 27484 | 10:14:24 INFO - PROCESS | 27484 | Cycle 1(1): loaded http://localhost:35159/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35159/tests/dromaeo/cssquery-yui.html) 10:14:25 INFO - PROCESS | 27484 | RSS: Main: 177520640 10:14:25 INFO - PROCESS | 27484 | 10:17:27 INFO - PROCESS | 27484 | Cycle 1(1): loaded http://localhost:35159/tests/dromaeo/cssquery-yui.html (next: http://localhost:35159/tests/dromaeo/cssquery-dojo.html) 10:17:28 INFO - PROCESS | 27484 | RSS: Main: 260112384 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | __start_tp_report 10:17:28 INFO - PROCESS | 27484 | _x_x_mozilla_page_load 10:17:28 INFO - PROCESS | 27484 | _x_x_mozilla_page_load_details 10:17:28 INFO - PROCESS | 27484 | |i|pagename|runs| 10:17:28 INFO - PROCESS | 27484 | |0;dojo.html;3408.113890388499;3438.260773933602;3456.9308613827725;3463.774854634447;3493.9650603493956;5650.350209725882;5708.914366284504;5742.28221472316;5749.971250143754;5764.423557644239;5767.192593036978;5804.2744656917885;5807.303123625171;5815.040518314484;5827.242458480402;5126.41046279677;5160.225966105096;5173.120569503193;5190.195519694454;5245.606579506552;4974.328465657126;4991.401031876162;5253.395859476171;5257.36911570612;5285.947140528584;5108.182690769478;5146.382434107913;5204.635675502716;5207;5224.138017227153;5232.6860388376435;5235.371755389339;5236.057509648262;5237.188235823453;5269.4730526947;4908.190148625473;5087.720175390352;5155;5204.869878253036;5208.765605547759;5435.891282174334;5567.972160139214;5656.604037717361;5803.53571714266;5810.796622118248;6204.286507051656;6221.556138378918;6239.469645080128;6249.093881387219;6250.406211409909;6258.906116408258;6264.342244064399;6278.968605156991;6293.402126797947;6295.055741638791;5549.944500554983;5584.46947539983;5764.193012978187;5791.9710401448565;5867.031939729982;6352.14246076783;6367.204099487564;6367.522435817332;6368.203974503187;6372.6495042773095;6083.69581520931;6108.602940808833;6133.754649814046;6138.416850399205;6141.477974372139;5923.940760592425;5960.403959604005;6096.420840020191;6232.034034724625;6238.750449982041;6142.897836592492;6181.258249010148;6193.6903154842075;6200.56596038282;6202.28673702521;5887.999040163182;5917.763289468373;5922.615030023035;6101.786437474667;6203.31763506014;4679.976600116977;4694.718316900997;4728.432588089451;4745.1221524018165;4752.714837109785;4093.570175131628;4103.528094269138;4227.492700875914;4235.02594403289;4257.850975215914;5922;5968.22413086296;5994.640321580632;6039.245094363205;6039.607425517327;6215.239779933166;6222.657753823583;6235.158253635792;6245.500359971192;6252.280987686474;5722.542196624343;6002.459778619946;6011.458968692838;6013.609115407485;6056.394360563996;5102.8214012510125;5178.948210517847;5222.660527065728;5270.393904700932;5278.023565640369;6490.707918143695;6525.21697396306;6526.67366631657;6530.249021362604;6533;6171.290301615371;6244.531660125418;6255.499560035095;6259.937400625936;6263.342349053284;6112.685862051923;6117.663528505975;6377.4579160771855;6383.96808015957;6530.967345163243;6064;6079.604825686316;6083.6349819011;6089.512838972783;6124;6201.81394558164;6227;6232.719527621359;6236.002239641635;6236.220472440945;5689.6586204827845;5780.537556995346;5986;5986.760529578768;6061.605995610271;6011.699415029144;6053.515718742402;6062.636241825505;6079.696015199311;6100.816975490742;6042.123892035718;6044.697765111815;6045.75816967334;6049.78825741097;6052.606580572248;5769.624974376652;5775.884482310415;5820.447057529697;6033.215681961317;6058.606190597597;5960.940390596039;5964.373740757158;5976.8804623908145;5994.55040871945;6003.669798161143 10:17:28 INFO - PROCESS | 27484 | |1;ext.html;756.6179079564819;820.9014918209815;847.6736456464259;913.2602591900561;930.1953809954383;31540.526892096637;44498.77750611246;45240.3213951791;45267;45282.773586132105;46335.76832115843;46572.53427465724;46885;46944;46974.06051878969;7651.693932242703;7670.846583068335;9314.441133531993;9474.431534107942;9477.668281610146;10739.570417183304;10915.945420272927;11315.151363647718;11337.829932551018;11344.205905586612;11080.722981925435;11134.832977505344;11135.554577816878;11137.832932506019;11148.275362101478;11057.11543076556;11099.611513597025;11104.722381940435;11106.666799995972;11109;10648.520816563274;10689.251752377337;10695.358278503314;10697.304675196086;10698.46507674621;40873.79563102195;40887.591124088976;40892.182156357005;40938.59061409377;41017.79491102525;4167.729097608646;4175.248455278049;4243.3634954757035;4310.719803212781;4344.913101737948;29694.109176724734;29947.850260748775;30014.84992575023;30651;31442.842785785924;4359.38968544404;4424.628534909752;4446.866594002185;4460.442444694413;4578.519255478193;229498;230982;231646.9999999983;231931;232102;53101.46898531043;53129.203061954104;53248.73375633097;53442.465575344526;53465.46534534683;55298.723506383016;55617.443825561226;55763.721181393834;55822.720886395306;55838;15176.241187940559;15622.687546249012;15639.139847308506;15736.449224277096;15750.291236894367;12243.877561224452;12453.128281020241;13796.586102416743;13893.235872027135;13905.304734763222;12758.10693251482;12932.482700691868;12981.935090324678;13096;13373.398197081155;5359.758810853521;5394.811181608625;5396.352437707422;5430.677935623112;5555.694436806015;1009.7627057641448;1010.2322235101376;1012.6506355307407;1013.9797204055877;1028.377831411985;28511.87411587729;28546.571801422997;28600.14199574016;28790.42419151647;29105;1068.0387651113904;1072.6621114348955;1092.5848177692585;1115.7880002799604;1116.4864162485185;14771.011826806916;15041.097534147986;15110.622234444007;15274.618134546503;15451.150186739613;312794;314474;314820;314911;315045;56852;57331.713341433024;57370.713146434835;57391.71304143452;57393;1505.728968785607;1531.5022617649308;1540.6995635850901;1563.2965165675491;1572.1431819658314;25508.617370739827;26829;27107.72892271052;27230.183094507196;27321;41279.58720412877;41369.79315103405;41374.79312603418;41446;41470.170596588505;274595;275749;276177;276266;277269;29451.263718407212;34381.82809085939;34465.48301775526;34606.82696586501;34762.82618586891;45567.54432455765;45608.31587526256;46195.538044620465;46242.53757462382;46447.07105857931;35142;35188.12029699176;35236.29527409386;35257.82371088128;35375.46936796002;34621;34623.82688086543;34685.65314346925;34739.826300868335;34781.304373912884;40167.1966560673;40255.59744402519;40291.597084029956;40615.3907691379;40893.38659920044;32713.018609441755;32833.835830820695;32952;33065;33172.1706957328;26440.074597389;26812.463750724484;27083.59374609343;27109.1867243983;27243.727562724118 10:17:28 INFO - PROCESS | 27484 | |2;jquery.html;428.4066567803591;456.16977101674945;460.1877685884415;473.1978552332013;487.7390596031123;19354.612907741855;19368.70946935797;21089.367318980418;21204.575908481842;21323.680144797803;22451.887740561273;22585.096596136136;22643.660345094835;22685.09259629613;22783.658245126335;6561.245456772473;6822.727090916357;6832.863342733142;7169.46229032822;7396.926030739677;7567.205443428444;7613.505122167069;7617.352525035379;7665;7667;7438.739644112457;7573.242675732437;7578.280063393996;7579.090509138911;7588.241175882423;7608.239176082375;7760.495567788075;7786.1435242123325;7804.219578042207;7819.296263336327;7013.89479157813;7265.309795569412;7585;7622;7626.046744156981;23926.64110038351;24475.020999160013;25704.22887313365;25965.870170649214;26067.608985865227;3008.1877892968996;3338.8832285479934;3465.5321531593163;3521.559805024372;3531.434970404723;15662.295196716175;16006.439774607832;16435.58911027222;16579;17116.315347386215;2217.9889100554556;2268.8411811173223;2307.573098976678;2322.4890524084844;2346.847454915442;113270;114312;115281;117066.41466792696;117928;14681.494439585156;16963.74554381685;18146.364877229233;18708.438746837615;19055.428337149908;23972.40068998289;29415.85292073526;32043.679563204536;32078.679213207568;32288.35423291521;10170.949145254375;10236.48817559134;10288.331258468175;10380.017591556096;10460.215483838743;8612.052674205832;8614.26778723803;8660.956695216484;8834.469931804113;8866.512341821133;9252.352335336462;9469.195118414875;9631.614735410505;9729;9733.902660973441;3279.6720327967014;3443.517907492953;3530.982345088258;3699.204670995749;3785.1861849702445;957.4494665567327;964.2767924056957;965.792354643755;970.7039352997404;971.2375785008734;20659.58680826375;21592.244271450738;21650.675239871416;21777.891110544664;21831.781682183293;977.237754551451;979.3683074416961;983.6704703924265;986.3243678080538;1001.7044971733357;11460.885391146148;11782.764344713058;11807.350595717318;11936.522539098512;12083.879161208275;643146;657443;658988;661007;665143;94934;116907.41546292214;122062;123221;126293;1293.4696774322483;1309.6725818545365;1311.3115614302567;1311.6851955530603;1327.349598696651;18304;18467.353642622445;18892.905535472233;19093.713594296376;19207.135678894203;33306.16734581558;44008.33987490126;51958;52465.475345247585;52490.212646811095;58612.41387586069;62877.68561157165;70783.64608176926;71586;71829;11366.374849383032;12083.818742718691;12260.08049396281;12684.589927914036;12986.870131298567;14579.416823327372;14583.270836457923;14626.268686565416;14673.486427975397;15030.473933412279;12220.87779122233;12478.937605311916;12706.36468176569;12732.23606583608;12919.870801291867;11713.590024349107;11807;11934.22427542207;12041.638750837488;12230.816537752118;12785.872141278469;12829.422675979416;12847.486100555872;12941.223526588436;12976.286304253357;12350.135490515577;12378.44297006655;12454.439550220442;12529.686757831127;12690.682732931751;12547.498100075894;12888.42002109926;12909.870901290868;12996.870031299566;13037 10:17:28 INFO - PROCESS | 27484 | |3;mootools.html;1541.3064121145487;1563.7185306644806;1566.6318415172439;1579.8341174176712;1589.459583741528;11090.611828586001;11801.350925699084;11813.114016448757;11818.290902545854;11820.40897955102;11205.159613029015;11480.081593472523;11499.42502874855;11499.885001149985;11548;2435.232901635979;2471.789897858675;2472.777450029497;2474.8515089094712;2474.9133780317693;2337.9666089147895;2412.9879350603223;2474.257722683197;2477.046337160188;2478.2317481580676;2350.635651474024;2421.922244601153;2429.647701083342;2450.534398464295;2463.963040554393;2345.777151170636;2395.6406539019113;2395.688560487134;2396.9041238350533;2412.9276121716375;2390.40239940015;2403.435192729707;2404.1945948107405;2408.0728919365997;2412.7707867752533;11351.262167959056;11486.597969071043;11527.250728702607;11541.422928853608;11554.248973816759;5695.857603559903;5830.183774271605;5836.445537673914;5840.97079514604;5843.970780146072;3365.680260375285;3391.4573668213216;3410.8806191783415;3550.0947258449187;3566.3045706087323;1369.5548946592398;1386.3899884050986;1392.9930350348284;1409.3235247081357;1468.4933697874217;17533.473995780147;17582.208800603876;17682.557936051573;17712.114394280365;17745.378911738157;4471.418715567021;4665.906681866411;4737.692050016738;4748.382710247645;4750.548697873696;13066.477340906258;13118.212907225407;13158.342082895817;13182.274974876475;13187.076904616584;5777.711114444261;5791.5656325775735;5795.275590551181;5813.273340832396;5820.88358232833;5254.842354729364;5283.656562323436;5352.143657014859;5358.410574836765;5387.488188622075;4012.7391719538136;4082.3876418537457;4107.938380924288;4179.373094035919;4224.239636865333;1030.6753372687579;1039.927205095636;1041.13065590233;1050.695298363466;1051.673981065872;1049.574922156528;1055.5672174408455;1056.3556230699267;1064.4305296666246;1065.94137322448;7041.5775053496955;7163.7492687755675;7178.318059784312;7275.163356213997;7361.521501102411;1049.8162821506205;1112.471576001409;1120.3781901045;1149.609132894803;1150.5455345138623;4422.358757980139;4548.204064288736;4551.453825540956;4557.886052848639;4597.471490581775;14551.708965820835;15127.243637818065;15138.016028958082;15139.318730657147;15158.242087895562;11921.52313907434;14877.33052012662;14962.102273863604;14992.175430351217;15010.849891500946;8913.37606367548;10680.572777088984;11014.339139651647;11349;11350.602728904614;7203.351698347174;7457.701691932262;7488.887666685113;7494.887576686463;7508.549487030796;13089.149205301625;13105.14816536922;13117.93441032827;13597.388117534536;13603.727925441633;13415.865841341461;13559.118657287245;13621.659458513617;13662.248576328397;13741.931290343884;9686.373575900301;10108.747281318027;10136.695899123039;10212.795744085226;10330.896691032993;11486.712832179262;11517.193796434169;11533.480993355486;11597.420128993685;11687.298762074302;9169.908300916906;9341.719748407559;9412.764680883032;9489.288303377136;9518.23854091685;9401.482918439551;9440.811183776148;9466.621335146518;9533.856992144985;9618.086281803218;8920.375573709778;8974.506402147683;9010.729678109667;9026.052264512131;9084.500352480412;6369.80890573265;6417.422431981144;6497.057926600522;6538.215414150332;6556.114924485133;6537.934620653733;6545.836354090995;6564.376384243489;6695.4308883745425;6796.218434880052 10:17:28 INFO - PROCESS | 27484 | |4;prototype.html;406.61981047720366;409.38387726471655;423.6123946588871;427.95934386233307;429.9462567179103;5281.551068159206;5313.38896026957;5313.785159978215;5321.733913304334;5329.573634109272;5089.211172268295;5215.243789650508;5221.537969368583;5224.503672151149;5232.189010703338;4389.802458889358;4390.64874810016;4398.252297109491;4400.317950717628;4401.185780630578;4405.405270288502;4417.204903117437;4428.712133711315;4464.620507256887;4543.977280113595;4277.8074986625525;4394.736315821061;4414.713043652168;4474.328850672393;4491.73049617024;4388.605025547716;4394.40675508807;4398.538153493901;4408.889777755549;4413.389112973774;4137.77242251676;4140.0955560442235;4155.293600087993;4166.812493437803;4170.894712901084;4990.600751939873;5102.443754654829;5120.871978200537;5136.306598609177;5166.974165129188;5079.415867175248;5100.97980403917;5107.825200203937;5114.616403769732;5128.153854613985;5529.889402211933;5534.418886016951;5535.363433205192;5539.473749993744;5584.329880414336;4443.933340999887;4600.976995115003;4601.401817763703;4628.375169352162;4630.050839577879;5529.834104976857;5594.300712410949;5649.830505084813;5750.223719797816;5781.167228058222;4700.858974230779;4721.787519561628;4723.551262630044;4751.3348131261655;4752.263399173134;4615.515370886076;4686.906261874743;4795.832145874878;4814;4821.2045012573235;4745.14587374269;4963.8262660806695;4999.825006124768;5097.209932460474;5133.972081027973;5508.504234618824;5543.501084902378;5548.805791797267;5552.889422115593;5557.77768889248;1589.2053973013494;1662.9440305405913;1663.8419350161714;1674.3302678928526;1684.082175214511;1255.9309237991997;1277.6422601671547;1289.5744404346542;1291.9677008074686;1292.7414517096431;5463.781448742086;5486.423925487846;5494.862628434321;5508.256385388001;5513.9448605514235;5614.85962850932;5705.726696290396;5734.5125664319;5738.102258359585;5775.5668324875805;5430.294061772023;5547.5007249347755;5567.721613919288;5574.247476590689;5601.943980560142;3031.9545206821917;3033.7106729639813;3037.635483741965;3038.5290280006634;3049.8322592257637;5808.477237048686;5833.100195835518;5967.6121052131475;6029.939700603026;6061.544919395902;5777;5791.478766910998;5827.825165245049;5831.970840145772;5834.3290521589715;5994.460498555151;5996.670183139882;5996.67018313997;6012;6031.215941927697;5406.729663516887;5654.208410822406;5667.659940403589;5673.347565030074;5709.9714501427225;5620.9156862647915;5629.8592535186945;5631.408702086386;5638.548916086621;5675.460831221028;5722.484976352148;5831.504322132666;5831.7375718091935;5863.472287494146;5897.852553686193;4665.440147182359;4668.206404911105;4721.763911804464;4755.690880092783;4769.594584460221;5497.367802702581;5675.631083979693;5721.170430287668;5774.249347584787;5808.564357673107;5522.337319521683;5548.556115510669;5562.554995600423;5567.192757050286;5623.353314368736;5517.613767036269;5590.468905453975;5602.803901863415;5614.129809879475;5663.461971112738;5241.240020197126;5507.586930980273;5517.641353312022;5549.445055494418;5578.3584887738425;5560;5561.833145005657;5571.582131340085;5595.810388078756;5601.243832082617;5329.62831539961;5512.641678290898;5514.160207493064;5535.924028489317;5568.665880047048 10:17:28 INFO - PROCESS | 27484 | |5;yui.html;840.4743745046136;882.496976723268;902.9006809250981;914.1048153735809;948.5921053946804;16461.75307370389;17122.40071597494;17251.654966900653;17295.913520432412;17315.39396121136;17198.656026879486;17478.12609369533;17652.646947061083;17795.911020444913;17837.91081044593;870.8867847179873;882.9072947340533;882.9911700882973;883.8630012348096;883.9116088391141;885.4598694796169;885.57049830832;914.6890057380489;915.1900567997337;916.1434059154678;952.8808898887639;954.4130359828724;959.2853324273426;959.9952000239989;961.418341903149;954.9904500954971;955.1117460761488;955.5938726041475;955.6941778630841;955.7945041816043;705.3792662457056;705.7000774670745;705.939995100417;706.1314583062862;706.6078326528774;17002.149892505324;17172.055536945463;17226.052567108803;17594.38419655306;17631.823681763148;16164.191790410432;16494.83505164945;16511.33954641813;16579;16862.325506979705;13300.667483312995;13729.725405491834;13806.516771912935;13860.792088118686;13943.721125577533;3985.06351007513;4054.290499162664;4054.7161698681102;4111.218868414991;4165.4584903962295;91782.5410872948;93364;94883.52558237164;94984.52507737417;95045.52477237569;12562.371881405894;12567.245965242115;12716.872831271568;12747.490100396066;12877.162984405984;25196.118135865156;25215.495690086096;25218.87390563072;25275.368115796886;25344.493110138064;8380.580970951429;8623.31013518917;8681.696140635047;8760.342974276955;8881.289496840367;5028.396592408934;5191.53653701137;5191.740412979336;5210.7915683373;5386.838394848082;3675.4119340905327;3820.8662696805477;3827.6363745444137;3830;3830.042489377656;667.5727534378002;670.165643773505;674.844785699292;684.5824047331124;690.5649440852231;746.8655641984386;750.0699133075057;780.3094261578544;783.6747749683817;788.4717239449583;11094.1124710023;11104.944475277573;11410.828837567444;11503.367314797766;11842.940785296018;844.1600607395558;851.3146916731966;857.1086070486749;871.4117970369975;882.633707011583;5308.92036619451;5349.598780091509;5391.514763671288;5431.429699881534;5460.290162278879;84762;86473;86475.56762216148;88122.55938720393;89160;43313.11841685296;70156.29843701498;70787.29212707806;71607.64196178986;73634.63182684052;46214.30678539824;50146.749266253435;60840.69579652162;68834.41074092491;69805.3019469809;11747.88252117502;12138.332391718188;12210;12265.386730663253;12409.193402428813;42351;42743.572564273956;42865.14269714526;42918.78540607277;43788.34317485304;61454.38545614487;61460;62472.375276246654;63567;63937.36062639314;14002.57992260234;14105.365258563184;14196;14274.14355138695;14561.781573276197;16873.325066997182;17136.314547417962;17311.13444327804;17441.651166976844;17583.64832703313;15393.538193854203;15588.766168507254;15719.842801571838;16698.83301166973;16725;16334.918325408296;17013.57466063358;17231.56921076983;17233.741493877853;17248.655026899643;13400.463981440633;15495.30271137779;15699.136547489541;15971.680566388839;16459.094749788397;10781.9460902695;11044.944775276072;11104.944475277573;11636.94181529087;11836.408179591159;11726.296422214353;12195.207311524722;12470;12534.623961281177;12757.55348562796 10:17:28 INFO - PROCESS | 27484 | __end_tp_report 10:17:28 INFO - PROCESS | 27484 | __start_cc_report 10:17:28 INFO - PROCESS | 27484 | _x_x_mozilla_cycle_collect,1390 10:17:28 INFO - PROCESS | 27484 | __end_cc_report 10:17:28 INFO - PROCESS | 27484 | __startTimestamp1491844648045__endTimestamp 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | ------- Summary: start ------- 10:17:28 INFO - PROCESS | 27484 | Number of tests: 6 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | [#0] dojo.html Cycles:165 Average:5728.88 Median:5986.76 stddev:663.98 (11.1%) stddev-sans-first:640.57 10:17:28 INFO - PROCESS | 27484 | Values: 3408.1 3438.3 3456.9 3463.8 3494.0 5650.4 5708.9 5742.3 5750.0 5764.4 5767.2 5804.3 5807.3 5815.0 5827.2 5126.4 5160.2 5173.1 5190.2 5245.6 4974.3 4991.4 5253.4 5257.4 5285.9 5108.2 5146.4 5204.6 5207.0 5224.1 5232.7 5235.4 5236.1 5237.2 5269.5 4908.2 5087.7 5155.0 5204.9 5208.8 5435.9 5568.0 5656.6 5803.5 5810.8 6204.3 6221.6 6239.5 6249.1 6250.4 6258.9 6264.3 6279.0 6293.4 6295.1 5549.9 5584.5 5764.2 5792.0 5867.0 6352.1 6367.2 6367.5 6368.2 6372.6 6083.7 6108.6 6133.8 6138.4 6141.5 5923.9 5960.4 6096.4 6232.0 6238.8 6142.9 6181.3 6193.7 6200.6 6202.3 5888.0 5917.8 5922.6 6101.8 6203.3 4680.0 4694.7 4728.4 4745.1 4752.7 4093.6 4103.5 4227.5 4235.0 4257.9 5922.0 5968.2 5994.6 6039.2 6039.6 6215.2 6222.7 6235.2 6245.5 6252.3 5722.5 6002.5 6011.5 6013.6 6056.4 5102.8 5178.9 5222.7 5270.4 5278.0 6490.7 6525.2 6526.7 6530.2 6533.0 6171.3 6244.5 6255.5 6259.9 6263.3 6112.7 6117.7 6377.5 6384.0 6531.0 6064.0 6079.6 6083.6 6089.5 6124.0 6201.8 6227.0 6232.7 6236.0 6236.2 5689.7 5780.5 5986.0 5986.8 6061.6 6011.7 6053.5 6062.6 6079.7 6100.8 6042.1 6044.7 6045.8 6049.8 6052.6 5769.6 5775.9 5820.4 6033.2 6058.6 5960.9 5964.4 5976.9 5994.6 6003.7 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | [#1] ext.html Cycles:180 Average:45092.85 Median:173292.76 stddev:71880.92 (41.5%) stddev-sans-first:72005.47 10:17:28 INFO - PROCESS | 27484 | Values: 756.6 820.9 847.7 913.3 930.2 31540.5 44498.8 45240.3 45267.0 45282.8 46335.8 46572.5 46885.0 46944.0 46974.1 7651.7 7670.8 9314.4 9474.4 9477.7 10739.6 10915.9 11315.2 11337.8 11344.2 11080.7 11134.8 11135.6 11137.8 11148.3 11057.1 11099.6 11104.7 11106.7 11109.0 10648.5 10689.3 10695.4 10697.3 10698.5 40873.8 40887.6 40892.2 40938.6 41017.8 4167.7 4175.2 4243.4 4310.7 4344.9 29694.1 29947.9 30014.8 30651.0 31442.8 4359.4 4424.6 4446.9 4460.4 4578.5 229498.0 230982.0 231647.0 231931.0 232102.0 53101.5 53129.2 53248.7 53442.5 53465.5 55298.7 55617.4 55763.7 55822.7 55838.0 15176.2 15622.7 15639.1 15736.4 15750.3 12243.9 12453.1 13796.6 13893.2 13905.3 12758.1 12932.5 12981.9 13096.0 13373.4 5359.8 5394.8 5396.4 5430.7 5555.7 1009.8 1010.2 1012.7 1014.0 1028.4 28511.9 28546.6 28600.1 28790.4 29105.0 1068.0 1072.7 1092.6 1115.8 1116.5 14771.0 15041.1 15110.6 15274.6 15451.2 312794.0 314474.0 314820.0 314911.0 315045.0 56852.0 57331.7 57370.7 57391.7 57393.0 1505.7 1531.5 1540.7 1563.3 1572.1 25508.6 26829.0 27107.7 27230.2 27321.0 41279.6 41369.8 41374.8 41446.0 41470.2 274595.0 275749.0 276177.0 276266.0 277269.0 29451.3 34381.8 34465.5 34606.8 34762.8 45567.5 45608.3 46195.5 46242.5 46447.1 35142.0 35188.1 35236.3 35257.8 35375.5 34621.0 34623.8 34685.7 34739.8 34781.3 40167.2 40255.6 40291.6 40615.4 40893.4 32713.0 32833.8 32952.0 33065.0 33172.2 26440.1 26812.5 27083.6 27109.2 27243.7 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | [#2] jquery.html Cycles:180 Average:37673.68 Median:22734.38 stddev:108370.58 (476.7%) stddev-sans-first:108638.50 10:17:28 INFO - PROCESS | 27484 | Values: 428.4 456.2 460.2 473.2 487.7 19354.6 19368.7 21089.4 21204.6 21323.7 22451.9 22585.1 22643.7 22685.1 22783.7 6561.2 6822.7 6832.9 7169.5 7396.9 7567.2 7613.5 7617.4 7665.0 7667.0 7438.7 7573.2 7578.3 7579.1 7588.2 7608.2 7760.5 7786.1 7804.2 7819.3 7013.9 7265.3 7585.0 7622.0 7626.0 23926.6 24475.0 25704.2 25965.9 26067.6 3008.2 3338.9 3465.5 3521.6 3531.4 15662.3 16006.4 16435.6 16579.0 17116.3 2218.0 2268.8 2307.6 2322.5 2346.8 113270.0 114312.0 115281.0 117066.4 117928.0 14681.5 16963.7 18146.4 18708.4 19055.4 23972.4 29415.9 32043.7 32078.7 32288.4 10170.9 10236.5 10288.3 10380.0 10460.2 8612.1 8614.3 8661.0 8834.5 8866.5 9252.4 9469.2 9631.6 9729.0 9733.9 3279.7 3443.5 3531.0 3699.2 3785.2 957.4 964.3 965.8 970.7 971.2 20659.6 21592.2 21650.7 21777.9 21831.8 977.2 979.4 983.7 986.3 1001.7 11460.9 11782.8 11807.4 11936.5 12083.9 643146.0 657443.0 658988.0 661007.0 665143.0 94934.0 116907.4 122062.0 123221.0 126293.0 1293.5 1309.7 1311.3 1311.7 1327.3 18304.0 18467.4 18892.9 19093.7 19207.1 33306.2 44008.3 51958.0 52465.5 52490.2 58612.4 62877.7 70783.6 71586.0 71829.0 11366.4 12083.8 12260.1 12684.6 12986.9 14579.4 14583.3 14626.3 14673.5 15030.5 12220.9 12478.9 12706.4 12732.2 12919.9 11713.6 11807.0 11934.2 12041.6 12230.8 12785.9 12829.4 12847.5 12941.2 12976.3 12350.1 12378.4 12454.4 12529.7 12690.7 12547.5 12888.4 12909.9 12996.9 13037.0 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | [#3] mootools.html Cycles:180 Average:7268.21 Median:2403.81 stddev:4679.80 (194.7%) stddev-sans-first:4673.14 10:17:28 INFO - PROCESS | 27484 | Values: 1541.3 1563.7 1566.6 1579.8 1589.5 11090.6 11801.4 11813.1 11818.3 11820.4 11205.2 11480.1 11499.4 11499.9 11548.0 2435.2 2471.8 2472.8 2474.9 2474.9 2338.0 2413.0 2474.3 2477.0 2478.2 2350.6 2421.9 2429.6 2450.5 2464.0 2345.8 2395.6 2395.7 2396.9 2412.9 2390.4 2403.4 2404.2 2408.1 2412.8 11351.3 11486.6 11527.3 11541.4 11554.2 5695.9 5830.2 5836.4 5841.0 5844.0 3365.7 3391.5 3410.9 3550.1 3566.3 1369.6 1386.4 1393.0 1409.3 1468.5 17533.5 17582.2 17682.6 17712.1 17745.4 4471.4 4665.9 4737.7 4748.4 4750.5 13066.5 13118.2 13158.3 13182.3 13187.1 5777.7 5791.6 5795.3 5813.3 5820.9 5254.8 5283.7 5352.1 5358.4 5387.5 4012.7 4082.4 4107.9 4179.4 4224.2 1030.7 1039.9 1041.1 1050.7 1051.7 1049.6 1055.6 1056.4 1064.4 1065.9 7041.6 7163.7 7178.3 7275.2 7361.5 1049.8 1112.5 1120.4 1149.6 1150.5 4422.4 4548.2 4551.5 4557.9 4597.5 14551.7 15127.2 15138.0 15139.3 15158.2 11921.5 14877.3 14962.1 14992.2 15010.8 8913.4 10680.6 11014.3 11349.0 11350.6 7203.4 7457.7 7488.9 7494.9 7508.5 13089.1 13105.1 13117.9 13597.4 13603.7 13415.9 13559.1 13621.7 13662.2 13741.9 9686.4 10108.7 10136.7 10212.8 10330.9 11486.7 11517.2 11533.5 11597.4 11687.3 9169.9 9341.7 9412.8 9489.3 9518.2 9401.5 9440.8 9466.6 9533.9 9618.1 8920.4 8974.5 9010.7 9026.1 9084.5 6369.8 6417.4 6497.1 6538.2 6556.1 6537.9 6545.8 6564.4 6695.4 6796.2 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | [#4] prototype.html Cycles:180 Average:4845.68 Median:5329.60 stddev:1292.86 (24.3%) stddev-sans-first:1252.82 10:17:28 INFO - PROCESS | 27484 | Values: 406.6 409.4 423.6 428.0 429.9 5281.6 5313.4 5313.8 5321.7 5329.6 5089.2 5215.2 5221.5 5224.5 5232.2 4389.8 4390.6 4398.3 4400.3 4401.2 4405.4 4417.2 4428.7 4464.6 4544.0 4277.8 4394.7 4414.7 4474.3 4491.7 4388.6 4394.4 4398.5 4408.9 4413.4 4137.8 4140.1 4155.3 4166.8 4170.9 4990.6 5102.4 5120.9 5136.3 5167.0 5079.4 5101.0 5107.8 5114.6 5128.2 5529.9 5534.4 5535.4 5539.5 5584.3 4443.9 4601.0 4601.4 4628.4 4630.1 5529.8 5594.3 5649.8 5750.2 5781.2 4700.9 4721.8 4723.6 4751.3 4752.3 4615.5 4686.9 4795.8 4814.0 4821.2 4745.1 4963.8 4999.8 5097.2 5134.0 5508.5 5543.5 5548.8 5552.9 5557.8 1589.2 1662.9 1663.8 1674.3 1684.1 1255.9 1277.6 1289.6 1292.0 1292.7 5463.8 5486.4 5494.9 5508.3 5513.9 5614.9 5705.7 5734.5 5738.1 5775.6 5430.3 5547.5 5567.7 5574.2 5601.9 3032.0 3033.7 3037.6 3038.5 3049.8 5808.5 5833.1 5967.6 6029.9 6061.5 5777.0 5791.5 5827.8 5832.0 5834.3 5994.5 5996.7 5996.7 6012.0 6031.2 5406.7 5654.2 5667.7 5673.3 5710.0 5620.9 5629.9 5631.4 5638.5 5675.5 5722.5 5831.5 5831.7 5863.5 5897.9 4665.4 4668.2 4721.8 4755.7 4769.6 5497.4 5675.6 5721.2 5774.2 5808.6 5522.3 5548.6 5562.6 5567.2 5623.4 5517.6 5590.5 5602.8 5614.1 5663.5 5241.2 5507.6 5517.6 5549.4 5578.4 5560.0 5561.8 5571.6 5595.8 5601.2 5329.6 5512.6 5514.2 5535.9 5568.7 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - PROCESS | 27484 | [#5] yui.html Cycles:180 Average:19267.56 Median:42547.29 stddev:24245.86 (57.0%) stddev-sans-first:24274.39 10:17:28 INFO - PROCESS | 27484 | Values: 840.5 882.5 902.9 914.1 948.6 16461.8 17122.4 17251.7 17295.9 17315.4 17198.7 17478.1 17652.6 17795.9 17837.9 870.9 882.9 883.0 883.9 883.9 885.5 885.6 914.7 915.2 916.1 952.9 954.4 959.3 960.0 961.4 955.0 955.1 955.6 955.7 955.8 705.4 705.7 705.9 706.1 706.6 17002.1 17172.1 17226.1 17594.4 17631.8 16164.2 16494.8 16511.3 16579.0 16862.3 13300.7 13729.7 13806.5 13860.8 13943.7 3985.1 4054.3 4054.7 4111.2 4165.5 91782.5 93364.0 94883.5 94984.5 95045.5 12562.4 12567.2 12716.9 12747.5 12877.2 25196.1 25215.5 25218.9 25275.4 25344.5 8380.6 8623.3 8681.7 8760.3 8881.3 5028.4 5191.5 5191.7 5210.8 5386.8 3675.4 3820.9 3827.6 3830.0 3830.0 667.6 670.2 674.8 684.6 690.6 746.9 750.1 780.3 783.7 788.5 11094.1 11104.9 11410.8 11503.4 11842.9 844.2 851.3 857.1 871.4 882.6 5308.9 5349.6 5391.5 5431.4 5460.3 84762.0 86473.0 86475.6 88122.6 89160.0 43313.1 70156.3 70787.3 71607.6 73634.6 46214.3 50146.7 60840.7 68834.4 69805.3 11747.9 12138.3 12210.0 12265.4 12409.2 42351.0 42743.6 42865.1 42918.8 43788.3 61454.4 61460.0 62472.4 63567.0 63937.4 14002.6 14105.4 14196.0 14274.1 14561.8 16873.3 17136.3 17311.1 17441.7 17583.6 15393.5 15588.8 15719.8 16698.8 16725.0 16334.9 17013.6 17231.6 17233.7 17248.7 13400.5 15495.3 15699.1 15971.7 16459.1 10781.9 11044.9 11104.9 11636.9 11836.4 11726.3 12195.2 12470.0 12534.6 12757.6 10:17:28 INFO - PROCESS | 27484 | -------- Summary: end -------- 10:17:28 INFO - PROCESS | 27484 | 10:17:28 INFO - TEST-INFO | 27484: exit 0 10:17:28 INFO - TEST-OK | dromaeo_css | took 1116051ms 10:17:28 INFO - TEST-START | kraken 10:17:28 INFO - operating with platform_type : linux_ 10:17:28 INFO - Initialising browser for kraken test... 10:17:28 INFO - TEST-INFO | started process 27809 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqIfOhG/profile http://localhost:35159/getInfo.html) 10:17:33 INFO - PROCESS | 27809 | __metrics Screen width/height:1600/1200 10:17:33 INFO - PROCESS | 27809 | colorDepth:24 10:17:33 INFO - PROCESS | 27809 | Browser inner width/height: 1024/697 10:17:33 INFO - PROCESS | 27809 | __metrics 10:17:33 INFO - PROCESS | 27809 | 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" 10:17:33 INFO - PROCESS | 27809 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:34 INFO - PROCESS | 27809 | console.error: PushService: 10:17:34 INFO - PROCESS | 27809 | stateChangeProcessEnqueue: Error transitioning state 10:17:34 INFO - PROCESS | 27809 | UnknownError 10:17:34 INFO - TEST-INFO | 27809: exit 0 10:17:34 INFO - Browser initialized. 10:17:34 INFO - Running cycle 1/1 for kraken test... 10:17:34 INFO - TEST-INFO | started process 27902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqIfOhG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:46 INFO - PROCESS | 27902 | RSS: Main: 139816960 10:17:46 INFO - PROCESS | 27902 | 10:17:49 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35159/tests/kraken/driver.html?testName=audio-beat-detection) 10:17:50 INFO - PROCESS | 27902 | RSS: Main: 183250944 10:17:50 INFO - PROCESS | 27902 | 10:17:52 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35159/tests/kraken/driver.html?testName=audio-dft) 10:17:53 INFO - PROCESS | 27902 | RSS: Main: 174116864 10:17:53 INFO - PROCESS | 27902 | 10:17:56 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35159/tests/kraken/driver.html?testName=audio-fft) 10:17:56 INFO - PROCESS | 27902 | RSS: Main: 265498624 10:17:56 INFO - PROCESS | 27902 | 10:17:58 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35159/tests/kraken/driver.html?testName=audio-oscillator) 10:17:59 INFO - PROCESS | 27902 | RSS: Main: 171679744 10:17:59 INFO - PROCESS | 27902 | 10:18:01 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35159/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:01 INFO - PROCESS | 27902 | RSS: Main: 174682112 10:18:01 INFO - PROCESS | 27902 | 10:18:04 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35159/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:05 INFO - PROCESS | 27902 | RSS: Main: 213114880 10:18:05 INFO - PROCESS | 27902 | 10:18:08 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35159/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:09 INFO - PROCESS | 27902 | RSS: Main: 211963904 10:18:09 INFO - PROCESS | 27902 | 10:18:12 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35159/tests/kraken/driver.html?testName=json-parse-financial) 10:18:12 INFO - PROCESS | 27902 | RSS: Main: 210657280 10:18:12 INFO - PROCESS | 27902 | 10:18:14 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35159/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:14 INFO - PROCESS | 27902 | RSS: Main: 173170688 10:18:14 INFO - PROCESS | 27902 | 10:18:16 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:17 INFO - PROCESS | 27902 | RSS: Main: 179253248 10:18:17 INFO - PROCESS | 27902 | 10:18:19 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:19 INFO - PROCESS | 27902 | RSS: Main: 189267968 10:18:19 INFO - PROCESS | 27902 | 10:18:22 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:23 INFO - PROCESS | 27902 | RSS: Main: 197926912 10:18:23 INFO - PROCESS | 27902 | 10:18:25 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:26 INFO - PROCESS | 27902 | RSS: Main: 194727936 10:18:26 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | Cycle 1(1): loaded http://localhost:35159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35159/tests/kraken/driver.html?testName=ai-astar) 10:18:28 INFO - PROCESS | 27902 | RSS: Main: 244625408 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | __start_tp_report 10:18:28 INFO - PROCESS | 27902 | _x_x_mozilla_page_load 10:18:28 INFO - PROCESS | 27902 | _x_x_mozilla_page_load_details 10:18:28 INFO - PROCESS | 27902 | |i|pagename|runs| 10:18:28 INFO - PROCESS | 27902 | |0;ai-astar;104;111;112;114;112;111;112;111;112;113 10:18:28 INFO - PROCESS | 27902 | |1;audio-beat-detection;137;116;118;163;115;115;118;121;141;116 10:18:28 INFO - PROCESS | 27902 | |2;audio-dft;161;164;161;160;161;160;161;161;159;161 10:18:28 INFO - PROCESS | 27902 | |3;audio-fft;74;75;76;106;75;75;75;77;96;76 10:18:28 INFO - PROCESS | 27902 | |4;audio-oscillator;82;81;105;83;82;82;93;81;81;109 10:18:28 INFO - PROCESS | 27902 | |5;imaging-gaussian-blur;113;113;114;114;113;114;113;113;114;113 10:18:28 INFO - PROCESS | 27902 | |6;imaging-darkroom;147;146;146;146;147;146;146;146;145;146 10:18:28 INFO - PROCESS | 27902 | |7;imaging-desaturate;94;94;94;94;94;93;94;94;94;94 10:18:28 INFO - PROCESS | 27902 | |8;json-parse-financial;54;55;53;54;54;55;55;55;54;54 10:18:28 INFO - PROCESS | 27902 | |9;json-stringify-tinderbox;53;53;53;52;52;53;53;53;53;53 10:18:28 INFO - PROCESS | 27902 | |10;stanford-crypto-aes;79;84;76;76;76;77;75;78;75;83 10:18:28 INFO - PROCESS | 27902 | |11;stanford-crypto-ccm;127;140;126;122;121;130;120;127;163;141 10:18:28 INFO - PROCESS | 27902 | |12;stanford-crypto-pbkdf2;171;156;160;168;161;165;162;163;161;165 10:18:28 INFO - PROCESS | 27902 | |13;stanford-crypto-sha256-iterative;61;60;60;59;60;61;59;59;61;58 10:18:28 INFO - PROCESS | 27902 | __end_tp_report 10:18:28 INFO - PROCESS | 27902 | __start_cc_report 10:18:28 INFO - PROCESS | 27902 | _x_x_mozilla_cycle_collect,1673 10:18:28 INFO - PROCESS | 27902 | __end_cc_report 10:18:28 INFO - PROCESS | 27902 | __startTimestamp1491844708264__endTimestamp 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | ------- Summary: start ------- 10:18:28 INFO - PROCESS | 27902 | Number of tests: 14 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.70 (2.4%) stddev-sans-first:1.00 10:18:28 INFO - PROCESS | 27902 | Values: 104.0 111.0 112.0 114.0 112.0 111.0 112.0 111.0 112.0 113.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:119.50 stddev:16.02 (13.4%) stddev-sans-first:16.49 10:18:28 INFO - PROCESS | 27902 | Values: 137.0 116.0 118.0 163.0 115.0 115.0 118.0 121.0 141.0 116.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 10:18:28 INFO - PROCESS | 27902 | Values: 161.0 164.0 161.0 160.0 161.0 160.0 161.0 161.0 159.0 161.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.50 stddev:11.09 (14.7%) stddev-sans-first:11.51 10:18:28 INFO - PROCESS | 27902 | Values: 74.0 75.0 76.0 106.0 75.0 75.0 75.0 77.0 96.0 76.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:82.00 stddev:10.72 (13.1%) stddev-sans-first:11.16 10:18:28 INFO - PROCESS | 27902 | Values: 82.0 81.0 105.0 83.0 82.0 82.0 93.0 81.0 81.0 109.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:18:28 INFO - PROCESS | 27902 | Values: 113.0 113.0 114.0 114.0 113.0 114.0 113.0 113.0 114.0 113.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 10:18:28 INFO - PROCESS | 27902 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 145.0 146.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:18:28 INFO - PROCESS | 27902 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.67 (1.2%) stddev-sans-first:0.71 10:18:28 INFO - PROCESS | 27902 | Values: 54.0 55.0 53.0 54.0 54.0 55.0 55.0 55.0 54.0 54.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#9] json-stringify-tinderbox Cycles:10 Average:52.80 Median:53.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 10:18:28 INFO - PROCESS | 27902 | Values: 53.0 53.0 53.0 52.0 52.0 53.0 53.0 53.0 53.0 53.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:77.50 stddev:3.21 (4.1%) stddev-sans-first:3.38 10:18:28 INFO - PROCESS | 27902 | Values: 79.0 84.0 76.0 76.0 76.0 77.0 75.0 78.0 75.0 83.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:128.50 stddev:13.17 (10.2%) stddev-sans-first:13.85 10:18:28 INFO - PROCESS | 27902 | Values: 127.0 140.0 126.0 122.0 121.0 130.0 120.0 127.0 163.0 141.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:164.00 stddev:4.26 (2.6%) stddev-sans-first:3.46 10:18:28 INFO - PROCESS | 27902 | Values: 171.0 156.0 160.0 168.0 161.0 165.0 162.0 163.0 161.0 165.0 10:18:28 INFO - PROCESS | 27902 | 10:18:28 INFO - PROCESS | 27902 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:60.00 stddev:1.03 (1.7%) stddev-sans-first:1.00 10:18:28 INFO - PROCESS | 27902 | Values: 61.0 60.0 60.0 59.0 60.0 61.0 59.0 59.0 61.0 58.0 10:18:28 INFO - PROCESS | 27902 | -------- Summary: end -------- 10:18:28 INFO - PROCESS | 27902 | 10:18:29 INFO - TEST-INFO | 27902: exit 0 10:18:29 INFO - TEST-OK | kraken | took 60643ms 10:18:29 INFO - SUITE-END | took 1176s 10:18:29 INFO - Completed test suite (00:19:36) 10:18:29 INFO - javascript benchmark 10:18:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7943.754529301514, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.113890388499, 3438.260773933602, 3456.9308613827725, 3463.774854634447, 3493.9650603493956, 5650.350209725882, 5708.914366284504, 5742.28221472316, 5749.971250143754, 5764.423557644239, 5767.192593036978, 5804.2744656917885, 5807.303123625171, 5815.040518314484, 5827.242458480402, 5126.41046279677, 5160.225966105096, 5173.120569503193, 5190.195519694454, 5245.606579506552, 4974.328465657126, 4991.401031876162, 5253.395859476171, 5257.36911570612, 5285.947140528584, 5108.182690769478, 5146.382434107913, 5204.635675502716, 5207.0, 5224.138017227153, 5232.6860388376435, 5235.371755389339, 5236.057509648262, 5237.188235823453, 5269.4730526947, 4908.190148625473, 5087.720175390352, 5155.0, 5204.869878253036, 5208.765605547759, 5435.891282174334, 5567.972160139214, 5656.604037717361, 5803.53571714266, 5810.796622118248, 6204.286507051656, 6221.556138378918, 6239.469645080128, 6249.093881387219, 6250.406211409909, 6258.906116408258, 6264.342244064399, 6278.968605156991, 6293.402126797947, 6295.055741638791, 5549.944500554983, 5584.46947539983, 5764.193012978187, 5791.9710401448565, 5867.031939729982, 6352.14246076783, 6367.204099487564, 6367.522435817332, 6368.203974503187, 6372.6495042773095, 6083.69581520931, 6108.602940808833, 6133.754649814046, 6138.416850399205, 6141.477974372139, 5923.940760592425, 5960.403959604005, 6096.420840020191, 6232.034034724625, 6238.750449982041, 6142.897836592492, 6181.258249010148, 6193.6903154842075, 6200.56596038282, 6202.28673702521, 5887.999040163182, 5917.763289468373, 5922.615030023035, 6101.786437474667, 6203.31763506014, 4679.976600116977, 4694.718316900997, 4728.432588089451, 4745.1221524018165, 4752.714837109785, 4093.570175131628, 4103.528094269138, 4227.492700875914, 4235.02594403289, 4257.850975215914, 5922.0, 5968.22413086296, 5994.640321580632, 6039.245094363205, 6039.607425517327, 6215.239779933166, 6222.657753823583, 6235.158253635792, 6245.500359971192, 6252.280987686474, 5722.542196624343, 6002.459778619946, 6011.458968692838, 6013.609115407485, 6056.394360563996, 5102.8214012510125, 5178.948210517847, 5222.660527065728, 5270.393904700932, 5278.023565640369, 6490.707918143695, 6525.21697396306, 6526.67366631657, 6530.249021362604, 6533.0, 6171.290301615371, 6244.531660125418, 6255.499560035095, 6259.937400625936, 6263.342349053284, 6112.685862051923, 6117.663528505975, 6377.4579160771855, 6383.96808015957, 6530.967345163243, 6064.0, 6079.604825686316, 6083.6349819011, 6089.512838972783, 6124.0, 6201.81394558164, 6227.0, 6232.719527621359, 6236.002239641635, 6236.220472440945, 5689.6586204827845, 5780.537556995346, 5986.0, 5986.760529578768, 6061.605995610271, 6011.699415029144, 6053.515718742402, 6062.636241825505, 6079.696015199311, 6100.816975490742, 6042.123892035718, 6044.697765111815, 6045.75816967334, 6049.78825741097, 6052.606580572248, 5769.624974376652, 5775.884482310415, 5820.447057529697, 6033.215681961317, 6058.606190597597, 5960.940390596039, 5964.373740757158, 5976.8804623908145, 5994.55040871945, 6003.669798161143], "value": 5684.563532703956, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.6179079564819, 820.9014918209815, 847.6736456464259, 913.2602591900561, 930.1953809954383, 31540.526892096637, 44498.77750611246, 45240.3213951791, 45267.0, 45282.773586132105, 46335.76832115843, 46572.53427465724, 46885.0, 46944.0, 46974.06051878969, 7651.693932242703, 7670.846583068335, 9314.441133531993, 9474.431534107942, 9477.668281610146, 10739.570417183304, 10915.945420272927, 11315.151363647718, 11337.829932551018, 11344.205905586612, 11080.722981925435, 11134.832977505344, 11135.554577816878, 11137.832932506019, 11148.275362101478, 11057.11543076556, 11099.611513597025, 11104.722381940435, 11106.666799995972, 11109.0, 10648.520816563274, 10689.251752377337, 10695.358278503314, 10697.304675196086, 10698.46507674621, 40873.79563102195, 40887.591124088976, 40892.182156357005, 40938.59061409377, 41017.79491102525, 4167.729097608646, 4175.248455278049, 4243.3634954757035, 4310.719803212781, 4344.913101737948, 29694.109176724734, 29947.850260748775, 30014.84992575023, 30651.0, 31442.842785785924, 4359.38968544404, 4424.628534909752, 4446.866594002185, 4460.442444694413, 4578.519255478193, 229498.0, 230982.0, 231646.9999999983, 231931.0, 232102.0, 53101.46898531043, 53129.203061954104, 53248.73375633097, 53442.465575344526, 53465.46534534683, 55298.723506383016, 55617.443825561226, 55763.721181393834, 55822.720886395306, 55838.0, 15176.241187940559, 15622.687546249012, 15639.139847308506, 15736.449224277096, 15750.291236894367, 12243.877561224452, 12453.128281020241, 13796.586102416743, 13893.235872027135, 13905.304734763222, 12758.10693251482, 12932.482700691868, 12981.935090324678, 13096.0, 13373.398197081155, 5359.758810853521, 5394.811181608625, 5396.352437707422, 5430.677935623112, 5555.694436806015, 1009.7627057641448, 1010.2322235101376, 1012.6506355307407, 1013.9797204055877, 1028.377831411985, 28511.87411587729, 28546.571801422997, 28600.14199574016, 28790.42419151647, 29105.0, 1068.0387651113904, 1072.6621114348955, 1092.5848177692585, 1115.7880002799604, 1116.4864162485185, 14771.011826806916, 15041.097534147986, 15110.622234444007, 15274.618134546503, 15451.150186739613, 312794.0, 314474.0, 314820.0, 314911.0, 315045.0, 56852.0, 57331.713341433024, 57370.713146434835, 57391.71304143452, 57393.0, 1505.728968785607, 1531.5022617649308, 1540.6995635850901, 1563.2965165675491, 1572.1431819658314, 25508.617370739827, 26829.0, 27107.72892271052, 27230.183094507196, 27321.0, 41279.58720412877, 41369.79315103405, 41374.79312603418, 41446.0, 41470.170596588505, 274595.0, 275749.0, 276177.0, 276266.0, 277269.0, 29451.263718407212, 34381.82809085939, 34465.48301775526, 34606.82696586501, 34762.82618586891, 45567.54432455765, 45608.31587526256, 46195.538044620465, 46242.53757462382, 46447.07105857931, 35142.0, 35188.12029699176, 35236.29527409386, 35257.82371088128, 35375.46936796002, 34621.0, 34623.82688086543, 34685.65314346925, 34739.826300868335, 34781.304373912884, 40167.1966560673, 40255.59744402519, 40291.597084029956, 40615.3907691379, 40893.38659920044, 32713.018609441755, 32833.835830820695, 32952.0, 33065.0, 33172.1706957328, 26440.074597389, 26812.463750724484, 27083.59374609343, 27109.1867243983, 27243.727562724118], "value": 19142.74145232096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.4066567803591, 456.16977101674945, 460.1877685884415, 473.1978552332013, 487.7390596031123, 19354.612907741855, 19368.70946935797, 21089.367318980418, 21204.575908481842, 21323.680144797803, 22451.887740561273, 22585.096596136136, 22643.660345094835, 22685.09259629613, 22783.658245126335, 6561.245456772473, 6822.727090916357, 6832.863342733142, 7169.46229032822, 7396.926030739677, 7567.205443428444, 7613.505122167069, 7617.352525035379, 7665.0, 7667.0, 7438.739644112457, 7573.242675732437, 7578.280063393996, 7579.090509138911, 7588.241175882423, 7608.239176082375, 7760.495567788075, 7786.1435242123325, 7804.219578042207, 7819.296263336327, 7013.89479157813, 7265.309795569412, 7585.0, 7622.0, 7626.046744156981, 23926.64110038351, 24475.020999160013, 25704.22887313365, 25965.870170649214, 26067.608985865227, 3008.1877892968996, 3338.8832285479934, 3465.5321531593163, 3521.559805024372, 3531.434970404723, 15662.295196716175, 16006.439774607832, 16435.58911027222, 16579.0, 17116.315347386215, 2217.9889100554556, 2268.8411811173223, 2307.573098976678, 2322.4890524084844, 2346.847454915442, 113270.0, 114312.0, 115281.0, 117066.41466792696, 117928.0, 14681.494439585156, 16963.74554381685, 18146.364877229233, 18708.438746837615, 19055.428337149908, 23972.40068998289, 29415.85292073526, 32043.679563204536, 32078.679213207568, 32288.35423291521, 10170.949145254375, 10236.48817559134, 10288.331258468175, 10380.017591556096, 10460.215483838743, 8612.052674205832, 8614.26778723803, 8660.956695216484, 8834.469931804113, 8866.512341821133, 9252.352335336462, 9469.195118414875, 9631.614735410505, 9729.0, 9733.902660973441, 3279.6720327967014, 3443.517907492953, 3530.982345088258, 3699.204670995749, 3785.1861849702445, 957.4494665567327, 964.2767924056957, 965.792354643755, 970.7039352997404, 971.2375785008734, 20659.58680826375, 21592.244271450738, 21650.675239871416, 21777.891110544664, 21831.781682183293, 977.237754551451, 979.3683074416961, 983.6704703924265, 986.3243678080538, 1001.7044971733357, 11460.885391146148, 11782.764344713058, 11807.350595717318, 11936.522539098512, 12083.879161208275, 643146.0, 657443.0, 658988.0, 661007.0, 665143.0, 94934.0, 116907.41546292214, 122062.0, 123221.0, 126293.0, 1293.4696774322483, 1309.6725818545365, 1311.3115614302567, 1311.6851955530603, 1327.349598696651, 18304.0, 18467.353642622445, 18892.905535472233, 19093.713594296376, 19207.135678894203, 33306.16734581558, 44008.33987490126, 51958.0, 52465.475345247585, 52490.212646811095, 58612.41387586069, 62877.68561157165, 70783.64608176926, 71586.0, 71829.0, 11366.374849383032, 12083.818742718691, 12260.08049396281, 12684.589927914036, 12986.870131298567, 14579.416823327372, 14583.270836457923, 14626.268686565416, 14673.486427975397, 15030.473933412279, 12220.87779122233, 12478.937605311916, 12706.36468176569, 12732.23606583608, 12919.870801291867, 11713.590024349107, 11807.0, 11934.22427542207, 12041.638750837488, 12230.816537752118, 12785.872141278469, 12829.422675979416, 12847.486100555872, 12941.223526588436, 12976.286304253357, 12350.135490515577, 12378.44297006655, 12454.439550220442, 12529.686757831127, 12690.682732931751, 12547.498100075894, 12888.42002109926, 12909.870901290868, 12996.870031299566, 13037.0], "value": 11639.003126566573, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.3064121145487, 1563.7185306644806, 1566.6318415172439, 1579.8341174176712, 1589.459583741528, 11090.611828586001, 11801.350925699084, 11813.114016448757, 11818.290902545854, 11820.40897955102, 11205.159613029015, 11480.081593472523, 11499.42502874855, 11499.885001149985, 11548.0, 2435.232901635979, 2471.789897858675, 2472.777450029497, 2474.8515089094712, 2474.9133780317693, 2337.9666089147895, 2412.9879350603223, 2474.257722683197, 2477.046337160188, 2478.2317481580676, 2350.635651474024, 2421.922244601153, 2429.647701083342, 2450.534398464295, 2463.963040554393, 2345.777151170636, 2395.6406539019113, 2395.688560487134, 2396.9041238350533, 2412.9276121716375, 2390.40239940015, 2403.435192729707, 2404.1945948107405, 2408.0728919365997, 2412.7707867752533, 11351.262167959056, 11486.597969071043, 11527.250728702607, 11541.422928853608, 11554.248973816759, 5695.857603559903, 5830.183774271605, 5836.445537673914, 5840.97079514604, 5843.970780146072, 3365.680260375285, 3391.4573668213216, 3410.8806191783415, 3550.0947258449187, 3566.3045706087323, 1369.5548946592398, 1386.3899884050986, 1392.9930350348284, 1409.3235247081357, 1468.4933697874217, 17533.473995780147, 17582.208800603876, 17682.557936051573, 17712.114394280365, 17745.378911738157, 4471.418715567021, 4665.906681866411, 4737.692050016738, 4748.382710247645, 4750.548697873696, 13066.477340906258, 13118.212907225407, 13158.342082895817, 13182.274974876475, 13187.076904616584, 5777.711114444261, 5791.5656325775735, 5795.275590551181, 5813.273340832396, 5820.88358232833, 5254.842354729364, 5283.656562323436, 5352.143657014859, 5358.410574836765, 5387.488188622075, 4012.7391719538136, 4082.3876418537457, 4107.938380924288, 4179.373094035919, 4224.239636865333, 1030.6753372687579, 1039.927205095636, 1041.13065590233, 1050.695298363466, 1051.673981065872, 1049.574922156528, 1055.5672174408455, 1056.3556230699267, 1064.4305296666246, 1065.94137322448, 7041.5775053496955, 7163.7492687755675, 7178.318059784312, 7275.163356213997, 7361.521501102411, 1049.8162821506205, 1112.471576001409, 1120.3781901045, 1149.609132894803, 1150.5455345138623, 4422.358757980139, 4548.204064288736, 4551.453825540956, 4557.886052848639, 4597.471490581775, 14551.708965820835, 15127.243637818065, 15138.016028958082, 15139.318730657147, 15158.242087895562, 11921.52313907434, 14877.33052012662, 14962.102273863604, 14992.175430351217, 15010.849891500946, 8913.37606367548, 10680.572777088984, 11014.339139651647, 11349.0, 11350.602728904614, 7203.351698347174, 7457.701691932262, 7488.887666685113, 7494.887576686463, 7508.549487030796, 13089.149205301625, 13105.14816536922, 13117.93441032827, 13597.388117534536, 13603.727925441633, 13415.865841341461, 13559.118657287245, 13621.659458513617, 13662.248576328397, 13741.931290343884, 9686.373575900301, 10108.747281318027, 10136.695899123039, 10212.795744085226, 10330.896691032993, 11486.712832179262, 11517.193796434169, 11533.480993355486, 11597.420128993685, 11687.298762074302, 9169.908300916906, 9341.719748407559, 9412.764680883032, 9489.288303377136, 9518.23854091685, 9401.482918439551, 9440.811183776148, 9466.621335146518, 9533.856992144985, 9618.086281803218, 8920.375573709778, 8974.506402147683, 9010.729678109667, 9026.052264512131, 9084.500352480412, 6369.80890573265, 6417.422431981144, 6497.057926600522, 6538.215414150332, 6556.114924485133, 6537.934620653733, 6545.836354090995, 6564.376384243489, 6695.4308883745425, 6796.218434880052], "value": 5479.601184247305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.61981047720366, 409.38387726471655, 423.6123946588871, 427.95934386233307, 429.9462567179103, 5281.551068159206, 5313.38896026957, 5313.785159978215, 5321.733913304334, 5329.573634109272, 5089.211172268295, 5215.243789650508, 5221.537969368583, 5224.503672151149, 5232.189010703338, 4389.802458889358, 4390.64874810016, 4398.252297109491, 4400.317950717628, 4401.185780630578, 4405.405270288502, 4417.204903117437, 4428.712133711315, 4464.620507256887, 4543.977280113595, 4277.8074986625525, 4394.736315821061, 4414.713043652168, 4474.328850672393, 4491.73049617024, 4388.605025547716, 4394.40675508807, 4398.538153493901, 4408.889777755549, 4413.389112973774, 4137.77242251676, 4140.0955560442235, 4155.293600087993, 4166.812493437803, 4170.894712901084, 4990.600751939873, 5102.443754654829, 5120.871978200537, 5136.306598609177, 5166.974165129188, 5079.415867175248, 5100.97980403917, 5107.825200203937, 5114.616403769732, 5128.153854613985, 5529.889402211933, 5534.418886016951, 5535.363433205192, 5539.473749993744, 5584.329880414336, 4443.933340999887, 4600.976995115003, 4601.401817763703, 4628.375169352162, 4630.050839577879, 5529.834104976857, 5594.300712410949, 5649.830505084813, 5750.223719797816, 5781.167228058222, 4700.858974230779, 4721.787519561628, 4723.551262630044, 4751.3348131261655, 4752.263399173134, 4615.515370886076, 4686.906261874743, 4795.832145874878, 4814.0, 4821.2045012573235, 4745.14587374269, 4963.8262660806695, 4999.825006124768, 5097.209932460474, 5133.972081027973, 5508.504234618824, 5543.501084902378, 5548.805791797267, 5552.889422115593, 5557.77768889248, 1589.2053973013494, 1662.9440305405913, 1663.8419350161714, 1674.3302678928526, 1684.082175214511, 1255.9309237991997, 1277.6422601671547, 1289.5744404346542, 1291.9677008074686, 1292.7414517096431, 5463.781448742086, 5486.423925487846, 5494.862628434321, 5508.256385388001, 5513.9448605514235, 5614.85962850932, 5705.726696290396, 5734.5125664319, 5738.102258359585, 5775.5668324875805, 5430.294061772023, 5547.5007249347755, 5567.721613919288, 5574.247476590689, 5601.943980560142, 3031.9545206821917, 3033.7106729639813, 3037.635483741965, 3038.5290280006634, 3049.8322592257637, 5808.477237048686, 5833.100195835518, 5967.6121052131475, 6029.939700603026, 6061.544919395902, 5777.0, 5791.478766910998, 5827.825165245049, 5831.970840145772, 5834.3290521589715, 5994.460498555151, 5996.670183139882, 5996.67018313997, 6012.0, 6031.215941927697, 5406.729663516887, 5654.208410822406, 5667.659940403589, 5673.347565030074, 5709.9714501427225, 5620.9156862647915, 5629.8592535186945, 5631.408702086386, 5638.548916086621, 5675.460831221028, 5722.484976352148, 5831.504322132666, 5831.7375718091935, 5863.472287494146, 5897.852553686193, 4665.440147182359, 4668.206404911105, 4721.763911804464, 4755.690880092783, 4769.594584460221, 5497.367802702581, 5675.631083979693, 5721.170430287668, 5774.249347584787, 5808.564357673107, 5522.337319521683, 5548.556115510669, 5562.554995600423, 5567.192757050286, 5623.353314368736, 5517.613767036269, 5590.468905453975, 5602.803901863415, 5614.129809879475, 5663.461971112738, 5241.240020197126, 5507.586930980273, 5517.641353312022, 5549.445055494418, 5578.3584887738425, 5560.0, 5561.833145005657, 5571.582131340085, 5595.810388078756, 5601.243832082617, 5329.62831539961, 5512.641678290898, 5514.160207493064, 5535.924028489317, 5568.665880047048], "value": 4469.7316802962805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.4743745046136, 882.496976723268, 902.9006809250981, 914.1048153735809, 948.5921053946804, 16461.75307370389, 17122.40071597494, 17251.654966900653, 17295.913520432412, 17315.39396121136, 17198.656026879486, 17478.12609369533, 17652.646947061083, 17795.911020444913, 17837.91081044593, 870.8867847179873, 882.9072947340533, 882.9911700882973, 883.8630012348096, 883.9116088391141, 885.4598694796169, 885.57049830832, 914.6890057380489, 915.1900567997337, 916.1434059154678, 952.8808898887639, 954.4130359828724, 959.2853324273426, 959.9952000239989, 961.418341903149, 954.9904500954971, 955.1117460761488, 955.5938726041475, 955.6941778630841, 955.7945041816043, 705.3792662457056, 705.7000774670745, 705.939995100417, 706.1314583062862, 706.6078326528774, 17002.149892505324, 17172.055536945463, 17226.052567108803, 17594.38419655306, 17631.823681763148, 16164.191790410432, 16494.83505164945, 16511.33954641813, 16579.0, 16862.325506979705, 13300.667483312995, 13729.725405491834, 13806.516771912935, 13860.792088118686, 13943.721125577533, 3985.06351007513, 4054.290499162664, 4054.7161698681102, 4111.218868414991, 4165.4584903962295, 91782.5410872948, 93364.0, 94883.52558237164, 94984.52507737417, 95045.52477237569, 12562.371881405894, 12567.245965242115, 12716.872831271568, 12747.490100396066, 12877.162984405984, 25196.118135865156, 25215.495690086096, 25218.87390563072, 25275.368115796886, 25344.493110138064, 8380.580970951429, 8623.31013518917, 8681.696140635047, 8760.342974276955, 8881.289496840367, 5028.396592408934, 5191.53653701137, 5191.740412979336, 5210.7915683373, 5386.838394848082, 3675.4119340905327, 3820.8662696805477, 3827.6363745444137, 3830.0, 3830.042489377656, 667.5727534378002, 670.165643773505, 674.844785699292, 684.5824047331124, 690.5649440852231, 746.8655641984386, 750.0699133075057, 780.3094261578544, 783.6747749683817, 788.4717239449583, 11094.1124710023, 11104.944475277573, 11410.828837567444, 11503.367314797766, 11842.940785296018, 844.1600607395558, 851.3146916731966, 857.1086070486749, 871.4117970369975, 882.633707011583, 5308.92036619451, 5349.598780091509, 5391.514763671288, 5431.429699881534, 5460.290162278879, 84762.0, 86473.0, 86475.56762216148, 88122.55938720393, 89160.0, 43313.11841685296, 70156.29843701498, 70787.29212707806, 71607.64196178986, 73634.63182684052, 46214.30678539824, 50146.749266253435, 60840.69579652162, 68834.41074092491, 69805.3019469809, 11747.88252117502, 12138.332391718188, 12210.0, 12265.386730663253, 12409.193402428813, 42351.0, 42743.572564273956, 42865.14269714526, 42918.78540607277, 43788.34317485304, 61454.38545614487, 61460.0, 62472.375276246654, 63567.0, 63937.36062639314, 14002.57992260234, 14105.365258563184, 14196.0, 14274.14355138695, 14561.781573276197, 16873.325066997182, 17136.314547417962, 17311.13444327804, 17441.651166976844, 17583.64832703313, 15393.538193854203, 15588.766168507254, 15719.842801571838, 16698.83301166973, 16725.0, 16334.918325408296, 17013.57466063358, 17231.56921076983, 17233.741493877853, 17248.655026899643, 13400.463981440633, 15495.30271137779, 15699.136547489541, 15971.680566388839, 16459.094749788397, 10781.9460902695, 11044.944775276072, 11104.944475277573, 11636.94181529087, 11836.408179591159, 11726.296422214353, 12195.207311524722, 12470.0, 12534.623961281177, 12757.55348562796], "value": 8099.702066805356, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 112.0, 114.0, 112.0, 111.0, 112.0, 111.0, 112.0, 113.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 116.0, 118.0, 163.0, 115.0, 115.0, 118.0, 121.0, 141.0, 116.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 159.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 106.0, 75.0, 75.0, 75.0, 77.0, 96.0, 76.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 105.0, 83.0, 82.0, 82.0, 93.0, 81.0, 81.0, 109.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 53.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 52.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 76.0, 76.0, 76.0, 77.0, 75.0, 78.0, 75.0, 83.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 140.0, 126.0, 122.0, 121.0, 130.0, 120.0, 127.0, 163.0, 141.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 156.0, 160.0, 168.0, 161.0, 165.0, 162.0, 163.0, 161.0, 165.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 59.0, 60.0, 61.0, 59.0, 59.0, 61.0, 58.0], "value": 59.8, "unit": "score"}]}]} 10:18:29 INFO - Return code: 0 10:18:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:29 ERROR - # TBPL SUCCESS # 10:18:29 INFO - Running post-action listener: _resource_record_post_action 10:18:29 INFO - [mozharness: 2017-04-10 17:18:29.567305Z] Finished run-tests step (success) 10:18:29 INFO - Running post-run listener: _resource_record_post_run 10:18:29 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 363712512; Read time: 8; Write time: 421580 10:18:29 INFO - TinderboxPrint: CPU usage
13.2% 10:18:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 10:18:29 INFO - TinderboxPrint: I/O write bytes / time
363,712,512 / 421,580 10:18:29 INFO - TinderboxPrint: CPU idle
8,267.1 (86.8%) 10:18:29 INFO - TinderboxPrint: CPU user
1,204.9 (12.7%) 10:18:29 INFO - TinderboxPrint: Swap in / out
0 / 0 10:18:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111161344; Read time: 0; Write time: 192992 10:18:29 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 252551168; Read time: 8; Write time: 228588 10:18:30 INFO - Running post-run listener: _upload_blobber_files 10:18:30 INFO - Blob upload gear active. 10:18:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:30 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 10:18:30 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'] 10:18:30 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 10:18:30 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:31 INFO - (blobuploader) - INFO - Done attempting. 10:18:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:34 INFO - (blobuploader) - INFO - Done attempting. 10:18:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:35 INFO - (blobuploader) - INFO - Done attempting. 10:18:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:18:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:18:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:36 INFO - (blobuploader) - INFO - Done attempting. 10:18:36 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:36 INFO - Return code: 0 10:18:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a66d8c16f3e33e4fa15f7b1628efb7530626f178a6a6aafd2e67f11a90ea01dee91e5b14769a225fd308fafee0c42f2fb9daf6c74fe3e2676eb4c2b0aaf4362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a0015cb57db8151a42528a18d900372ec557a7bbfd6a7eeeb41c82e6b56fc9bda2de81d24ac2526a8ddaa6fb47d48e50cd5318e5d1afec9350df0ad06b396d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c0ec939046a1d129e15a760fd2657487eae26b58430a71c1396492d8a4a94c2e8887238e6a6371aa0e369d6f586fa41ef0d0cb379ce8029185453fc57e5f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9728ff406e26e7e16768c57941f9584377b6f58428c392c1235be42e3a2cfb4e82a2951f18574efb6a7946a149065ab03d4f77672b641517edea93201b605f4"} 10:18:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:36 INFO - Contents: 10:18:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a66d8c16f3e33e4fa15f7b1628efb7530626f178a6a6aafd2e67f11a90ea01dee91e5b14769a225fd308fafee0c42f2fb9daf6c74fe3e2676eb4c2b0aaf4362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a0015cb57db8151a42528a18d900372ec557a7bbfd6a7eeeb41c82e6b56fc9bda2de81d24ac2526a8ddaa6fb47d48e50cd5318e5d1afec9350df0ad06b396d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c0ec939046a1d129e15a760fd2657487eae26b58430a71c1396492d8a4a94c2e8887238e6a6371aa0e369d6f586fa41ef0d0cb379ce8029185453fc57e5f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9728ff406e26e7e16768c57941f9584377b6f58428c392c1235be42e3a2cfb4e82a2951f18574efb6a7946a149065ab03d4f77672b641517edea93201b605f4"} 10:18:36 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:36 INFO - Copying logs to upload dir... 10:18:36 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.254897 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-04-10 10:18:36.100093) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 10:18:36.103407) ========= 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=8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a66d8c16f3e33e4fa15f7b1628efb7530626f178a6a6aafd2e67f11a90ea01dee91e5b14769a225fd308fafee0c42f2fb9daf6c74fe3e2676eb4c2b0aaf4362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a0015cb57db8151a42528a18d900372ec557a7bbfd6a7eeeb41c82e6b56fc9bda2de81d24ac2526a8ddaa6fb47d48e50cd5318e5d1afec9350df0ad06b396d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c0ec939046a1d129e15a760fd2657487eae26b58430a71c1396492d8a4a94c2e8887238e6a6371aa0e369d6f586fa41ef0d0cb379ce8029185453fc57e5f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9728ff406e26e7e16768c57941f9584377b6f58428c392c1235be42e3a2cfb4e82a2951f18574efb6a7946a149065ab03d4f77672b641517edea93201b605f4"} build_url:https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/FZwn6AZsRuiLxUBTET_wTQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a66d8c16f3e33e4fa15f7b1628efb7530626f178a6a6aafd2e67f11a90ea01dee91e5b14769a225fd308fafee0c42f2fb9daf6c74fe3e2676eb4c2b0aaf4362", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a0015cb57db8151a42528a18d900372ec557a7bbfd6a7eeeb41c82e6b56fc9bda2de81d24ac2526a8ddaa6fb47d48e50cd5318e5d1afec9350df0ad06b396d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55c0ec939046a1d129e15a760fd2657487eae26b58430a71c1396492d8a4a94c2e8887238e6a6371aa0e369d6f586fa41ef0d0cb379ce8029185453fc57e5f83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9728ff406e26e7e16768c57941f9584377b6f58428c392c1235be42e3a2cfb4e82a2951f18574efb6a7946a149065ab03d4f77672b641517edea93201b605f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 10:18:36.135657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 10:18:36.136036) ========= 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=8783db0840cc980e80bb0bd400000236-1491843319.198893-1078530312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.35 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 10:18:36.492384) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 10:18:36.492734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 10:18:36.493279) ========= ========= Total master_lag: 0.45 =========