builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1491601940.47 results: success (0) revision: f6712e7858f391923006b174d27cb919c454a9ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.474514) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.474953) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.475227) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.490021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.490285) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-07 14:52:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.0M=0s 2017-04-07 14:52:20 (50.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128114 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.632265) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.632575) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015406 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:20.681315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 14:52:20.681639) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6712e7858f391923006b174d27cb919c454a9ce --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 f6712e7858f391923006b174d27cb919c454a9ce --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=9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-07 14:52:20,882 truncating revision to first 12 chars 2017-04-07 14:52:20,882 Setting DEBUG logging. 2017-04-07 14:52:20,883 attempt 1/10 2017-04-07 14:52:20,883 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f6712e7858f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 14:52:22,208 unpacking tar archive at: mozilla-aurora-f6712e7858f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.659210 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 14:52:22.355343) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:22.356101) ========= script_repo_revision: f6712e7858f391923006b174d27cb919c454a9ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:22.356984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:22.357580) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 14:52:22.368692) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-07 14:52:22.369095) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:52:22 INFO - MultiFileLogger online at 20170407 14:52:22 in /builds/slave/test 14:52:22 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 14:52:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:52:22 INFO - {'append_to_log': False, 14:52:22 INFO - 'base_work_dir': '/builds/slave/test', 14:52:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:52:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:52:22 INFO - 'branch': 'Mozilla-Aurora', 14:52:22 INFO - 'buildbot_json_path': 'buildprops.json', 14:52:22 INFO - 'config_files': ('talos/linux_config.py',), 14:52:22 INFO - 'default_actions': ('clobber', 14:52:22 INFO - 'read-buildbot-config', 14:52:22 INFO - 'download-and-extract', 14:52:22 INFO - 'populate-webroot', 14:52:22 INFO - 'create-virtualenv', 14:52:22 INFO - 'install', 14:52:22 INFO - 'run-tests'), 14:52:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:52:22 INFO - 'download_minidump_stackwalk': True, 14:52:22 INFO - 'download_symbols': 'ondemand', 14:52:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:52:22 INFO - 'tooltool.py': '/tools/tooltool.py', 14:52:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:52:22 INFO - '/tools/misc-python/virtualenv.py')}, 14:52:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:52:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:52:22 INFO - 'gecko_profile': False, 14:52:22 INFO - 'gecko_profile_interval': 0, 14:52:22 INFO - 'installer_path': 'installer.exe', 14:52:22 INFO - 'log_level': 'info', 14:52:22 INFO - 'log_name': 'talos', 14:52:22 INFO - 'log_to_console': True, 14:52:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:52:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:52:22 INFO - 'opt_config_files': (), 14:52:22 INFO - 'pip_index': False, 14:52:22 INFO - 'suite': 'dromaeojs', 14:52:22 INFO - 'system_bits': '32', 14:52:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:52:22 INFO - 'title': 'talos-linux64-ix-040', 14:52:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:52:22 INFO - 'use_talos_json': True, 14:52:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:52:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:52:22 INFO - 'work_dir': 'build'} 14:52:22 INFO - [mozharness: 2017-04-07 21:52:22.667577Z] Running clobber step. 14:52:22 INFO - Running pre-action listener: _resource_record_pre_action 14:52:22 INFO - Running main action method: clobber 14:52:22 INFO - rmtree: /builds/slave/test/build 14:52:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:52:24 INFO - Running post-action listener: _resource_record_post_action 14:52:24 INFO - [mozharness: 2017-04-07 21:52:24.134841Z] Finished clobber step (success) 14:52:24 INFO - [mozharness: 2017-04-07 21:52:24.134940Z] Running read-buildbot-config step. 14:52:24 INFO - Running pre-action listener: _resource_record_pre_action 14:52:24 INFO - Running main action method: read_buildbot_config 14:52:24 INFO - Using buildbot properties: 14:52:24 INFO - { 14:52:24 INFO - "project": "", 14:52:24 INFO - "product": "firefox", 14:52:24 INFO - "who": "ryanvm@gmail.com", 14:52:24 INFO - "installer_path": "public/build/target.tar.bz2", 14:52:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:52:24 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 14:52:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:52:24 INFO - "stage_platform": "linux64-pgo", 14:52:24 INFO - "basedir": "/builds/slave/test", 14:52:24 INFO - "buildnumber": 1146, 14:52:24 INFO - "platform": "ubuntu64_hw", 14:52:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:52:24 INFO - "slavebuilddir": "test", 14:52:24 INFO - "taskId": "b07mAI9xTNmV7RsX5fUmWg", 14:52:24 INFO - "branch": "mozilla-aurora", 14:52:24 INFO - "script_repo_revision": "production", 14:52:24 INFO - "revision": "f6712e7858f391923006b174d27cb919c454a9ce", 14:52:24 INFO - "slavename": "talos-linux64-ix-040", 14:52:24 INFO - "repo_path": "releases/mozilla-aurora" 14:52:24 INFO - } 14:52:24 INFO - Finding installer, test and symbols from parent task. 14:52:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b07mAI9xTNmV7RsX5fUmWg'}, attempt #1 14:52:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b07mAI9xTNmV7RsX5fUmWg'}, attempt #1 14:52:25 INFO - Task dependencies: GX2SqMHHQIqt6fImp4dIdw 14:52:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw'}, attempt #1 14:52:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2 14:52:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/test_packages.json 14:52:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.crashreporter-symbols.zip 14:52:25 INFO - Running post-action listener: _resource_record_post_action 14:52:25 INFO - [mozharness: 2017-04-07 21:52:25.497299Z] Finished read-buildbot-config step (success) 14:52:25 INFO - [mozharness: 2017-04-07 21:52:25.497560Z] Running download-and-extract step. 14:52:25 INFO - Running pre-action listener: _resource_record_pre_action 14:52:25 INFO - Running main action method: download_and_extract 14:52:25 INFO - mkdir: /builds/slave/test/build/tests 14:52:25 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')]} 14:52:25 INFO - trying https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.test_packages.json 14:52:25 INFO - Downloading https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:52:26 INFO - Downloaded 896 bytes. 14:52:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:52:26 INFO - Using the following test package requirements: 14:52:26 INFO - {u'common': [u'target.common.tests.zip'], 14:52:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:52:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:52:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:52:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:52:26 INFO - u'mozbase': [u'target.common.tests.zip'], 14:52:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:52:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:52:26 INFO - u'web-platform': [u'target.common.tests.zip', 14:52:26 INFO - u'target.web-platform.tests.zip'], 14:52:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:52:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:52:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.common.tests.zip 14:52:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:52:26 INFO - Fetch https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.common.tests.zip into memory 14:52:27 INFO - Expected file size: 59541645 14:52:27 INFO - Obtained file size: 59541645 14:52:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:52:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.common.tests.zip 14:52:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:52:36 INFO - Fetch https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.common.tests.zip into memory 14:52:38 INFO - Expected file size: 59541645 14:52:38 INFO - Obtained file size: 59541645 14:52:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.talos.tests.zip 14:52:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:52:48 INFO - Fetch https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.talos.tests.zip into memory 14:52:49 INFO - Expected file size: 14404424 14:52:49 INFO - Obtained file size: 14404424 14:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:49 INFO - trying https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2 14:52:49 INFO - Downloading https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:52:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:52:50 INFO - Downloaded 66637871 bytes. 14:52:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2 14:52:50 INFO - mkdir: /builds/slave/test/properties 14:52:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:52:50 INFO - Writing to file /builds/slave/test/properties/build_url 14:52:50 INFO - Contents: 14:52:50 INFO - build_url:https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2 14:52:50 INFO - Running post-action listener: _resource_record_post_action 14:52:50 INFO - Running post-action listener: set_extra_try_arguments 14:52:50 INFO - [mozharness: 2017-04-07 21:52:50.852406Z] Finished download-and-extract step (success) 14:52:50 INFO - [mozharness: 2017-04-07 21:52:50.852503Z] Running populate-webroot step. 14:52:50 INFO - Running pre-action listener: _resource_record_pre_action 14:52:50 INFO - Running main action method: populate_webroot 14:52:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:52:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:52:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:52:50 INFO - '--tppagecycles', 14:52:50 INFO - '7'], 14:52:50 INFO - 'tests': ['tsvgm']}}, 14:52:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:52:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:52:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:52:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:52:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:52:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:52:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['damp', 'tps']}, 14:52:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'tests': ['damp', 'tps']}, 14:52:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['dromaeo_dom']}, 14:52:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:52:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['basic_compositor_video']}, 14:52:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:52:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['a11yr', 14:52:50 INFO - 'ts_paint', 14:52:50 INFO - 'tpaint', 14:52:50 INFO - 'sessionrestore', 14:52:50 INFO - 'sessionrestore_no_auto_restore', 14:52:50 INFO - 'tabpaint']}, 14:52:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:52:50 INFO - 'ts_paint', 14:52:50 INFO - 'tpaint', 14:52:50 INFO - 'sessionrestore', 14:52:50 INFO - 'sessionrestore_no_auto_restore', 14:52:50 INFO - 'tabpaint']}, 14:52:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:52:50 INFO - 'ts_paint', 14:52:50 INFO - 'tpaint', 14:52:50 INFO - 'sessionrestore', 14:52:50 INFO - 'sessionrestore_no_auto_restore', 14:52:50 INFO - 'tabpaint']}, 14:52:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:52:50 INFO - 'ts_paint', 14:52:50 INFO - 'tpaint', 14:52:50 INFO - 'sessionrestore', 14:52:50 INFO - 'sessionrestore_no_auto_restore', 14:52:50 INFO - 'tabpaint']}, 14:52:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['a11yr', 14:52:50 INFO - 'ts_paint', 14:52:50 INFO - 'tpaint', 14:52:50 INFO - 'sessionrestore', 14:52:50 INFO - 'sessionrestore_no_auto_restore', 14:52:50 INFO - 'tabpaint']}, 14:52:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['a11yr', 14:52:50 INFO - 'ts_paint', 14:52:50 INFO - 'tpaint', 14:52:50 INFO - 'sessionrestore', 14:52:50 INFO - 'sessionrestore_no_auto_restore', 14:52:50 INFO - 'tabpaint']}, 14:52:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['tsvgx', 14:52:50 INFO - 'tsvgr_opacity', 14:52:50 INFO - 'tart', 14:52:50 INFO - 'tscrollx', 14:52:50 INFO - 'cart', 14:52:50 INFO - 'tsvg_static']}, 14:52:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:52:50 INFO - 'tsvgr_opacity', 14:52:50 INFO - 'tart', 14:52:50 INFO - 'tscrollx', 14:52:50 INFO - 'cart', 14:52:50 INFO - 'tsvg_static']}, 14:52:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'talos_options': ['--disable-e10s'], 14:52:50 INFO - 'tests': ['tp5o']}, 14:52:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:52:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'talos_options': ['--disable-e10s', 14:52:50 INFO - '--xperf_path', 14:52:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:52:50 INFO - 'tests': ['tp5n']}, 14:52:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:52:50 INFO - 'talos_options': ['--xperf_path', 14:52:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:52:50 INFO - 'tests': ['tp5n']}}, 14:52:50 INFO - 'talos.zip': {'path': '', 14:52:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:52:50 INFO - Running post-action listener: _resource_record_post_action 14:52:50 INFO - [mozharness: 2017-04-07 21:52:50.865587Z] Finished populate-webroot step (success) 14:52:50 INFO - [mozharness: 2017-04-07 21:52:50.865677Z] Running create-virtualenv step. 14:52:50 INFO - Running pre-action listener: _resource_record_pre_action 14:52:50 INFO - Running main action method: create_virtualenv 14:52:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:52:50 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 14:52:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:52:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:52:50 INFO - Using real prefix '/usr' 14:52:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:52:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:52:53 INFO - Installing pip.................done. 14:52:53 INFO - Return code: 0 14:52:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:52:53 INFO - Reading from file tmpfile_stdout 14:52:53 INFO - Output received: 14:52:53 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) 14:52:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:52:53 INFO - Using env: {'DISPLAY': ':0', 14:52:53 INFO - 'HOME': '/home/cltbld', 14:52:53 INFO - 'LANG': 'en_US.UTF-8', 14:52:53 INFO - 'LANGUAGE': 'en_US:en', 14:52:53 INFO - 'LOGNAME': 'cltbld', 14:52:53 INFO - 'MAIL': '/var/mail/cltbld', 14:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:53 INFO - 'MOZ_NO_REMOTE': '1', 14:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:53 INFO - 'NO_EM_RESTART': '1', 14:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:53 INFO - 'PWD': '/builds/slave/test', 14:52:53 INFO - 'SHELL': '/bin/bash', 14:52:53 INFO - 'SHLVL': '1', 14:52:53 INFO - 'TERM': 'linux', 14:52:53 INFO - 'TMOUT': '86400', 14:52:53 INFO - 'USER': 'cltbld', 14:52:53 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:53 INFO - '_': '/tools/buildbot/bin/python'} 14:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:53 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 14:52:53 INFO - Cleaning up... 14:52:53 INFO - Return code: 0 14:52:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:52:53 INFO - Using env: {'DISPLAY': ':0', 14:52:53 INFO - 'HOME': '/home/cltbld', 14:52:53 INFO - 'LANG': 'en_US.UTF-8', 14:52:53 INFO - 'LANGUAGE': 'en_US:en', 14:52:53 INFO - 'LOGNAME': 'cltbld', 14:52:53 INFO - 'MAIL': '/var/mail/cltbld', 14:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:53 INFO - 'MOZ_NO_REMOTE': '1', 14:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:53 INFO - 'NO_EM_RESTART': '1', 14:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:53 INFO - 'PWD': '/builds/slave/test', 14:52:53 INFO - 'SHELL': '/bin/bash', 14:52:53 INFO - 'SHLVL': '1', 14:52:53 INFO - 'TERM': 'linux', 14:52:53 INFO - 'TMOUT': '86400', 14:52:53 INFO - 'USER': 'cltbld', 14:52:53 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:53 INFO - '_': '/tools/buildbot/bin/python'} 14:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:54 INFO - Downloading/unpacking psutil>=3.1.1 14:52:54 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 14:52:54 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 14:52:54 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 14:52:54 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 14:52:54 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 14:52:54 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 14:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:52:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:52:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:52:56 INFO - Installing collected packages: psutil 14:52:56 INFO - Running setup.py install for psutil 14:52:56 INFO - building 'psutil._psutil_linux' extension 14:52:56 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 14:52:56 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 14:52:56 INFO - building 'psutil._psutil_posix' extension 14:52:56 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 14:52:56 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 14:52:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:52:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:52:56 INFO - Successfully installed psutil 14:52:56 INFO - Cleaning up... 14:52:56 INFO - Return code: 0 14:52:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:52:56 INFO - Using env: {'DISPLAY': ':0', 14:52:56 INFO - 'HOME': '/home/cltbld', 14:52:56 INFO - 'LANG': 'en_US.UTF-8', 14:52:56 INFO - 'LANGUAGE': 'en_US:en', 14:52:56 INFO - 'LOGNAME': 'cltbld', 14:52:56 INFO - 'MAIL': '/var/mail/cltbld', 14:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:56 INFO - 'MOZ_NO_REMOTE': '1', 14:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:56 INFO - 'NO_EM_RESTART': '1', 14:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:56 INFO - 'PWD': '/builds/slave/test', 14:52:56 INFO - 'SHELL': '/bin/bash', 14:52:56 INFO - 'SHLVL': '1', 14:52:56 INFO - 'TERM': 'linux', 14:52:56 INFO - 'TMOUT': '86400', 14:52:56 INFO - 'USER': 'cltbld', 14:52:56 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:56 INFO - '_': '/tools/buildbot/bin/python'} 14:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:52:57 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 14:52:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:52:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:52:59 INFO - Installing collected packages: mozsystemmonitor 14:52:59 INFO - Running setup.py install for mozsystemmonitor 14:52:59 INFO - Successfully installed mozsystemmonitor 14:52:59 INFO - Cleaning up... 14:52:59 INFO - Return code: 0 14:52:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:59 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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:52:59 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 14:52:59 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 14:52:59 INFO - Using env: {'DISPLAY': ':0', 14:52:59 INFO - 'HOME': '/home/cltbld', 14:52:59 INFO - 'LANG': 'en_US.UTF-8', 14:52:59 INFO - 'LANGUAGE': 'en_US:en', 14:52:59 INFO - 'LOGNAME': 'cltbld', 14:52:59 INFO - 'MAIL': '/var/mail/cltbld', 14:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:59 INFO - 'MOZ_NO_REMOTE': '1', 14:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:59 INFO - 'NO_EM_RESTART': '1', 14:52:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:59 INFO - 'PWD': '/builds/slave/test', 14:52:59 INFO - 'SHELL': '/bin/bash', 14:52:59 INFO - 'SHLVL': '1', 14:52:59 INFO - 'TERM': 'linux', 14:52:59 INFO - 'TMOUT': '86400', 14:52:59 INFO - 'USER': 'cltbld', 14:52:59 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:59 INFO - '_': '/tools/buildbot/bin/python'} 14:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:59 INFO - Downloading/unpacking jsonschema==2.5.1 14:52:59 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 14:52:59 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 14:52:59 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 14:52:59 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 14:52:59 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 14:52:59 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 14:53:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:53:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:53:01 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 14:53:01 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 14:53:01 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 14:53:01 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 14:53:01 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 14:53:01 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 14:53:01 INFO - Downloading functools32-3.2.3-2.tar.gz 14:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:53:01 INFO - warning: no files found matching '*.txt' 14:53:01 INFO - no previously-included directories found matching 'build' 14:53:01 INFO - no previously-included directories found matching 'dist' 14:53:01 INFO - no previously-included directories found matching '.git*' 14:53:01 INFO - Installing collected packages: jsonschema, functools32 14:53:01 INFO - Running setup.py install for functools32 14:53:01 INFO - warning: no files found matching '*.txt' 14:53:01 INFO - no previously-included directories found matching 'build' 14:53:01 INFO - no previously-included directories found matching 'dist' 14:53:01 INFO - no previously-included directories found matching '.git*' 14:53:01 INFO - Successfully installed jsonschema functools32 14:53:01 INFO - Cleaning up... 14:53:01 INFO - Return code: 0 14:53:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:01 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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:53:01 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 14:53:01 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 14:53:01 INFO - Using env: {'DISPLAY': ':0', 14:53:01 INFO - 'HOME': '/home/cltbld', 14:53:01 INFO - 'LANG': 'en_US.UTF-8', 14:53:01 INFO - 'LANGUAGE': 'en_US:en', 14:53:01 INFO - 'LOGNAME': 'cltbld', 14:53:01 INFO - 'MAIL': '/var/mail/cltbld', 14:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:01 INFO - 'MOZ_NO_REMOTE': '1', 14:53:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:01 INFO - 'NO_EM_RESTART': '1', 14:53:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:01 INFO - 'PWD': '/builds/slave/test', 14:53:01 INFO - 'SHELL': '/bin/bash', 14:53:01 INFO - 'SHLVL': '1', 14:53:01 INFO - 'TERM': 'linux', 14:53:01 INFO - 'TMOUT': '86400', 14:53:01 INFO - 'USER': 'cltbld', 14:53:01 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:01 INFO - '_': '/tools/buildbot/bin/python'} 14:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:53:02 INFO - Cleaning up... 14:53:02 INFO - Return code: 0 14:53:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:02 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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:53:02 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 14:53:02 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 14:53:02 INFO - Using env: {'DISPLAY': ':0', 14:53:02 INFO - 'HOME': '/home/cltbld', 14:53:02 INFO - 'LANG': 'en_US.UTF-8', 14:53:02 INFO - 'LANGUAGE': 'en_US:en', 14:53:02 INFO - 'LOGNAME': 'cltbld', 14:53:02 INFO - 'MAIL': '/var/mail/cltbld', 14:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:02 INFO - 'MOZ_NO_REMOTE': '1', 14:53:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:02 INFO - 'NO_EM_RESTART': '1', 14:53:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:02 INFO - 'PWD': '/builds/slave/test', 14:53:02 INFO - 'SHELL': '/bin/bash', 14:53:02 INFO - 'SHLVL': '1', 14:53:02 INFO - 'TERM': 'linux', 14:53:02 INFO - 'TMOUT': '86400', 14:53:02 INFO - 'USER': 'cltbld', 14:53:02 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:02 INFO - '_': '/tools/buildbot/bin/python'} 14:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:02 INFO - Downloading/unpacking blobuploader==1.2.4 14:53:02 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 14:53:02 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 14:53:02 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 14:53:02 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 14:53:02 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 14:53:02 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 14:53:04 INFO - Downloading blobuploader-1.2.4.tar.gz 14:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:53:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:53:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:05 INFO - Downloading docopt-0.6.1.tar.gz 14:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:53:05 INFO - Installing collected packages: blobuploader, requests, docopt 14:53:05 INFO - Running setup.py install for blobuploader 14:53:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:53:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:53:05 INFO - Running setup.py install for requests 14:53:05 INFO - Running setup.py install for docopt 14:53:05 INFO - Successfully installed blobuploader requests docopt 14:53:05 INFO - Cleaning up... 14:53:05 INFO - Return code: 0 14:53:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:05 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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:53:05 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 14:53:05 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 14:53:05 INFO - Using env: {'DISPLAY': ':0', 14:53:05 INFO - 'HOME': '/home/cltbld', 14:53:05 INFO - 'LANG': 'en_US.UTF-8', 14:53:05 INFO - 'LANGUAGE': 'en_US:en', 14:53:05 INFO - 'LOGNAME': 'cltbld', 14:53:05 INFO - 'MAIL': '/var/mail/cltbld', 14:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:05 INFO - 'MOZ_NO_REMOTE': '1', 14:53:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:05 INFO - 'NO_EM_RESTART': '1', 14:53:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:05 INFO - 'PWD': '/builds/slave/test', 14:53:05 INFO - 'SHELL': '/bin/bash', 14:53:05 INFO - 'SHLVL': '1', 14:53:05 INFO - 'TERM': 'linux', 14:53:05 INFO - 'TMOUT': '86400', 14:53:05 INFO - 'USER': 'cltbld', 14:53:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:05 INFO - '_': '/tools/buildbot/bin/python'} 14:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:06 INFO - Running setup.py (path:/tmp/pip-YNvyBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:06 INFO - Running setup.py (path:/tmp/pip-dxDqcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:06 INFO - Running setup.py (path:/tmp/pip-bOIT5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:06 INFO - Running setup.py (path:/tmp/pip-b0MTTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:06 INFO - Running setup.py (path:/tmp/pip-HoP27x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:06 INFO - Running setup.py (path:/tmp/pip-dBRIYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:06 INFO - Running setup.py (path:/tmp/pip-PPxqEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:06 INFO - Running setup.py (path:/tmp/pip-F8HfIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:06 INFO - Running setup.py (path:/tmp/pip-JDFcpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:06 INFO - Running setup.py (path:/tmp/pip-zUm9aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:06 INFO - Running setup.py (path:/tmp/pip-LS_t0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:06 INFO - Running setup.py (path:/tmp/pip-R1xQyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:06 INFO - Running setup.py (path:/tmp/pip-BV_JzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:06 INFO - Running setup.py (path:/tmp/pip-tXrn93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:07 INFO - Running setup.py (path:/tmp/pip-fMYYIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:07 INFO - Running setup.py (path:/tmp/pip-TPTc0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:07 INFO - Running setup.py (path:/tmp/pip-DgboE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:53:07 INFO - Running setup.py install for manifestparser 14:53:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:53:07 INFO - Running setup.py install for mozcrash 14:53:07 INFO - Running setup.py install for mozdebug 14:53:07 INFO - Running setup.py install for mozdevice 14:53:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:53:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:53:07 INFO - Running setup.py install for mozfile 14:53:07 INFO - Running setup.py install for mozhttpd 14:53:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:53:07 INFO - Running setup.py install for mozinfo 14:53:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:53:07 INFO - Running setup.py install for mozInstall 14:53:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Running setup.py install for mozleak 14:53:08 INFO - Running setup.py install for mozlog 14:53:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Running setup.py install for moznetwork 14:53:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Running setup.py install for mozprocess 14:53:08 INFO - Running setup.py install for mozprofile 14:53:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Running setup.py install for mozrunner 14:53:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:53:08 INFO - Running setup.py install for mozscreenshot 14:53:08 INFO - Running setup.py install for moztest 14:53:08 INFO - Running setup.py install for mozversion 14:53:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:53:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:53:09 INFO - Cleaning up... 14:53:09 INFO - Return code: 0 14:53:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:09 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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:53:09 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 14:53:09 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 14:53:09 INFO - Using env: {'DISPLAY': ':0', 14:53:09 INFO - 'HOME': '/home/cltbld', 14:53:09 INFO - 'LANG': 'en_US.UTF-8', 14:53:09 INFO - 'LANGUAGE': 'en_US:en', 14:53:09 INFO - 'LOGNAME': 'cltbld', 14:53:09 INFO - 'MAIL': '/var/mail/cltbld', 14:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:09 INFO - 'MOZ_NO_REMOTE': '1', 14:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:09 INFO - 'NO_EM_RESTART': '1', 14:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:09 INFO - 'PWD': '/builds/slave/test', 14:53:09 INFO - 'SHELL': '/bin/bash', 14:53:09 INFO - 'SHLVL': '1', 14:53:09 INFO - 'TERM': 'linux', 14:53:09 INFO - 'TMOUT': '86400', 14:53:09 INFO - 'USER': 'cltbld', 14:53:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:09 INFO - '_': '/tools/buildbot/bin/python'} 14:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:09 INFO - Running setup.py (path:/tmp/pip-PnHO4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:09 INFO - Running setup.py (path:/tmp/pip-FUs9vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:09 INFO - Running setup.py (path:/tmp/pip-_tB1nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:09 INFO - Running setup.py (path:/tmp/pip-K8O2Y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:09 INFO - Running setup.py (path:/tmp/pip-tXnmeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:09 INFO - Running setup.py (path:/tmp/pip-IkRtW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:09 INFO - Running setup.py (path:/tmp/pip-08p24Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:09 INFO - Running setup.py (path:/tmp/pip-09L5oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:09 INFO - Running setup.py (path:/tmp/pip-2SkkVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:09 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)) 14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:09 INFO - Running setup.py (path:/tmp/pip-OHfFuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:10 INFO - Running setup.py (path:/tmp/pip-QepbwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:10 INFO - Running setup.py (path:/tmp/pip-CV4V8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:10 INFO - Running setup.py (path:/tmp/pip-6ctjRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:10 INFO - Running setup.py (path:/tmp/pip-blsR5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:10 INFO - Running setup.py (path:/tmp/pip-sR8Z4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:10 INFO - Running setup.py (path:/tmp/pip-qtmRRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:10 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)) 14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:10 INFO - Running setup.py (path:/tmp/pip-q8n5HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:10 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)) 14:53:10 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)) 14:53:10 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)) 14:53:10 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)) 14:53:10 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)) 14:53:10 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)) 14:53:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:53: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 14:53: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 14:53: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 14:53: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 14:53: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 14:53: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 14:53:12 INFO - Downloading blessings-1.6.tar.gz 14:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:53:12 INFO - Installing collected packages: blessings 14:53:12 INFO - Running setup.py install for blessings 14:53:12 INFO - Successfully installed blessings 14:53:12 INFO - Cleaning up... 14:53:12 INFO - Return code: 0 14:53:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:53:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:13 INFO - Reading from file tmpfile_stdout 14:53:13 INFO - Current package versions: 14:53:13 INFO - argparse == 1.2.1 14:53:13 INFO - blessings == 1.6 14:53:13 INFO - blobuploader == 1.2.4 14:53:13 INFO - docopt == 0.6.1 14:53:13 INFO - functools32 == 3.2.3-2 14:53:13 INFO - jsonschema == 2.5.1 14:53:13 INFO - manifestparser == 1.1 14:53:13 INFO - mozInstall == 1.12 14:53:13 INFO - mozcrash == 1.0 14:53:13 INFO - mozdebug == 0.1 14:53:13 INFO - mozdevice == 0.49 14:53:13 INFO - mozfile == 1.2 14:53:13 INFO - mozhttpd == 0.7 14:53:13 INFO - mozinfo == 0.9 14:53:13 INFO - mozleak == 0.1 14:53:13 INFO - mozlog == 3.4 14:53:13 INFO - moznetwork == 0.27 14:53:13 INFO - mozprocess == 0.25 14:53:13 INFO - mozprofile == 0.28 14:53:13 INFO - mozrunner == 6.13 14:53:13 INFO - mozscreenshot == 0.1 14:53:13 INFO - mozsystemmonitor == 0.3 14:53:13 INFO - moztest == 0.8 14:53:13 INFO - mozversion == 1.4 14:53:13 INFO - psutil == 3.1.1 14:53:13 INFO - requests == 1.2.3 14:53:13 INFO - wsgiref == 0.1.2 14:53:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:13 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 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:53:13 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 14:53:13 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 14:53:13 INFO - Using env: {'DISPLAY': ':0', 14:53:13 INFO - 'HOME': '/home/cltbld', 14:53:13 INFO - 'LANG': 'en_US.UTF-8', 14:53:13 INFO - 'LANGUAGE': 'en_US:en', 14:53:13 INFO - 'LOGNAME': 'cltbld', 14:53:13 INFO - 'MAIL': '/var/mail/cltbld', 14:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:13 INFO - 'MOZ_NO_REMOTE': '1', 14:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:13 INFO - 'NO_EM_RESTART': '1', 14:53:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:13 INFO - 'PWD': '/builds/slave/test', 14:53:13 INFO - 'SHELL': '/bin/bash', 14:53:13 INFO - 'SHLVL': '1', 14:53:13 INFO - 'TERM': 'linux', 14:53:13 INFO - 'TMOUT': '86400', 14:53:13 INFO - 'USER': 'cltbld', 14:53:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:13 INFO - '_': '/tools/buildbot/bin/python'} 14:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 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)) 14:53:13 INFO - Cleaning up... 14:53:13 INFO - Return code: 0 14:53:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e88b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, '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': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', '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 14:53: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', 'jsonschema'] in /builds/slave/test/build 14:53: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 jsonschema 14:53:13 INFO - Using env: {'DISPLAY': ':0', 14:53:13 INFO - 'HOME': '/home/cltbld', 14:53:13 INFO - 'LANG': 'en_US.UTF-8', 14:53:13 INFO - 'LANGUAGE': 'en_US:en', 14:53:13 INFO - 'LOGNAME': 'cltbld', 14:53:13 INFO - 'MAIL': '/var/mail/cltbld', 14:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:13 INFO - 'MOZ_NO_REMOTE': '1', 14:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:13 INFO - 'NO_EM_RESTART': '1', 14:53:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:13 INFO - 'PWD': '/builds/slave/test', 14:53:13 INFO - 'SHELL': '/bin/bash', 14:53:13 INFO - 'SHLVL': '1', 14:53:13 INFO - 'TERM': 'linux', 14:53:13 INFO - 'TMOUT': '86400', 14:53:13 INFO - 'USER': 'cltbld', 14:53:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:13 INFO - '_': '/tools/buildbot/bin/python'} 14:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:53:13 INFO - Cleaning up... 14:53:13 INFO - Return code: 0 14:53:13 INFO - Running post-action listener: _resource_record_post_action 14:53:13 INFO - Running post-action listener: _start_resource_monitoring 14:53:13 INFO - Starting resource monitoring. 14:53:13 INFO - [mozharness: 2017-04-07 21:53:13.532783Z] Finished create-virtualenv step (success) 14:53:13 INFO - [mozharness: 2017-04-07 21:53:13.533251Z] Running install step. 14:53:13 INFO - Running pre-action listener: _resource_record_pre_action 14:53:13 INFO - Running main action method: install 14:53:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:13 INFO - Reading from file tmpfile_stdout 14:53:13 INFO - Detecting whether we're running mozinstall >=1.0... 14:53:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:53:13 INFO - Reading from file tmpfile_stdout 14:53:13 INFO - Output received: 14:53:13 INFO - Usage: mozinstall [options] installer 14:53:13 INFO - Options: 14:53:13 INFO - -h, --help show this help message and exit 14:53:13 INFO - -d DEST, --destination=DEST 14:53:13 INFO - Directory to install application into. [default: 14:53:13 INFO - "/builds/slave/test"] 14:53:13 INFO - --app=APP Application being installed. [default: firefox] 14:53:13 INFO - mkdir: /builds/slave/test/build/application 14:53:13 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'] 14:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:53:30 INFO - Reading from file tmpfile_stdout 14:53:30 INFO - Output received: 14:53:30 INFO - /builds/slave/test/build/application/firefox/firefox 14:53:30 INFO - Running post-action listener: _resource_record_post_action 14:53:30 INFO - [mozharness: 2017-04-07 21:53:30.332465Z] Finished install step (success) 14:53:30 INFO - [mozharness: 2017-04-07 21:53:30.332613Z] Running run-tests step. 14:53:30 INFO - Running pre-action listener: _resource_record_pre_action 14:53:30 INFO - Running main action method: run_tests 14:53:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:53:30 INFO - Python 2.7.3 14:53:30 INFO - Return code: 0 14:53:30 INFO - grabbing minidump binary from tooltool 14:53: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')]} 14:53:30 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 0x1e86290>, '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 0x1e86b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e87040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:53:30 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 14:53:30 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 14:53:30 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 14:53:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:53:30 INFO - Return code: 0 14:53:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:53:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:53:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:53:30 INFO - ENV: RUST_BACKTRACE is now 1 14:53:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:53:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:53:30 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/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:53:30 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/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 14:53:30 INFO - Using env: {'DISPLAY': ':0', 14:53:30 INFO - 'HOME': '/home/cltbld', 14:53:30 INFO - 'LANG': 'en_US.UTF-8', 14:53:30 INFO - 'LANGUAGE': 'en_US:en', 14:53:30 INFO - 'LOGNAME': 'cltbld', 14:53:30 INFO - 'MAIL': '/var/mail/cltbld', 14:53:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:53:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:30 INFO - 'MOZ_NO_REMOTE': '1', 14:53:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:53:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:30 INFO - 'NO_EM_RESTART': '1', 14:53:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:30 INFO - 'PWD': '/builds/slave/test', 14:53:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:53:30 INFO - 'RUST_BACKTRACE': '1', 14:53:30 INFO - 'SHELL': '/bin/bash', 14:53:30 INFO - 'SHLVL': '1', 14:53:30 INFO - 'TERM': 'linux', 14:53:30 INFO - 'TMOUT': '86400', 14:53:30 INFO - 'USER': 'cltbld', 14:53:30 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528', 14:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:30 INFO - '_': '/tools/buildbot/bin/python'} 14:53:30 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/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 14:53:30 INFO - mozversion application_buildid: 20170407204744 14:53:30 INFO - mozversion application_changeset: f6712e7858f391923006b174d27cb919c454a9ce 14:53:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:53:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:53:30 INFO - mozversion application_name: Firefox 14:53:30 INFO - mozversion application_remotingname: firefox-dev 14:53:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:53:30 INFO - mozversion application_vendor: Mozilla 14:53:30 INFO - mozversion application_version: 54.0a2 14:53:30 INFO - mozversion platform_buildid: 20170407204744 14:53:30 INFO - mozversion platform_changeset: f6712e7858f391923006b174d27cb919c454a9ce 14:53:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:53:30 INFO - mozversion platform_version: 54.0a2 14:53:30 INFO - using testdate: 1491602010 14:53:30 INFO - actual date: 1491602010 14:53:30 INFO - starting webserver on 'localhost:41745' 14:53:30 INFO - SUITE-START | Running 2 tests 14:53:30 INFO - TEST-START | dromaeo_css 14:53:30 INFO - operating with platform_type : linux_ 14:53:30 INFO - Initialising browser for dromaeo_css test... 14:53:30 INFO - TEST-INFO | started process 26395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBSTE9c/profile http://localhost:41745/getInfo.html) 14:53:34 INFO - PROCESS | 26395 | __metrics Screen width/height:1600/1200 14:53:34 INFO - PROCESS | 26395 | colorDepth:24 14:53:34 INFO - PROCESS | 26395 | Browser inner width/height: 1024/697 14:53:34 INFO - PROCESS | 26395 | __metrics 14:53:35 INFO - PROCESS | 26395 | 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" 14:53:35 INFO - PROCESS | 26395 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:53:35 INFO - PROCESS | 26395 | console.error: PushService: 14:53:35 INFO - PROCESS | 26395 | stateChangeProcessEnqueue: Error transitioning state 14:53:35 INFO - PROCESS | 26395 | UnknownError 14:53:36 INFO - TEST-INFO | 26395: exit 0 14:53:36 INFO - Browser initialized. 14:53:36 INFO - Running cycle 1/1 for dromaeo_css test... 14:53:36 INFO - TEST-INFO | started process 26485 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBSTE9c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:48 INFO - PROCESS | 26485 | RSS: Main: 140120064 14:53:48 INFO - PROCESS | 26485 | 14:56:49 INFO - PROCESS | 26485 | Cycle 1(1): loaded http://localhost:41745/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41745/tests/dromaeo/cssquery-ext.html) 14:56:50 INFO - PROCESS | 26485 | RSS: Main: 281698304 14:56:50 INFO - PROCESS | 26485 | 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | A coding exception was thrown and uncaught in a Task. 14:58:38 INFO - PROCESS | 26485 | 14:58:38 ERROR - PROCESS | 26485 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:58:38 INFO - PROCESS | 26485 | Full stack: 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | A coding exception was thrown and uncaught in a Task. 14:58:38 INFO - PROCESS | 26485 | 14:58:38 ERROR - PROCESS | 26485 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:58:38 INFO - PROCESS | 26485 | Full stack: 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | A coding exception was thrown and uncaught in a Task. 14:58:38 INFO - PROCESS | 26485 | 14:58:38 ERROR - PROCESS | 26485 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:58:38 INFO - PROCESS | 26485 | Full stack: 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | ************************* 14:58:38 INFO - PROCESS | 26485 | A coding exception was thrown and uncaught in a Task. 14:58:38 INFO - PROCESS | 26485 | 14:58:38 ERROR - PROCESS | 26485 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:58:38 INFO - PROCESS | 26485 | Full stack: 14:58:38 INFO - PROCESS | 26485 | ************************* 14:59:53 INFO - PROCESS | 26485 | Cycle 1(1): loaded http://localhost:41745/tests/dromaeo/cssquery-ext.html (next: http://localhost:41745/tests/dromaeo/cssquery-jquery.html) 14:59:53 INFO - PROCESS | 26485 | RSS: Main: 223772672 14:59:53 INFO - PROCESS | 26485 | 15:02:55 INFO - PROCESS | 26485 | Cycle 1(1): loaded http://localhost:41745/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41745/tests/dromaeo/cssquery-mootools.html) 15:02:56 INFO - PROCESS | 26485 | RSS: Main: 203575296 15:02:56 INFO - PROCESS | 26485 | 15:05:58 INFO - PROCESS | 26485 | Cycle 1(1): loaded http://localhost:41745/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41745/tests/dromaeo/cssquery-prototype.html) 15:05:58 INFO - PROCESS | 26485 | RSS: Main: 172220416 15:05:58 INFO - PROCESS | 26485 | 15:09:01 INFO - PROCESS | 26485 | Cycle 1(1): loaded http://localhost:41745/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41745/tests/dromaeo/cssquery-yui.html) 15:09:02 INFO - PROCESS | 26485 | RSS: Main: 175988736 15:09:02 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | Cycle 1(1): loaded http://localhost:41745/tests/dromaeo/cssquery-yui.html (next: http://localhost:41745/tests/dromaeo/cssquery-dojo.html) 15:12:04 INFO - PROCESS | 26485 | RSS: Main: 254918656 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | __start_tp_report 15:12:04 INFO - PROCESS | 26485 | _x_x_mozilla_page_load 15:12:04 INFO - PROCESS | 26485 | _x_x_mozilla_page_load_details 15:12:04 INFO - PROCESS | 26485 | |i|pagename|runs| 15:12:04 INFO - PROCESS | 26485 | |0;dojo.html;3344.1639590102473;3376.712979396751;3382.458806590946;3416.2313479467107;3433.193199598094;5571.5542756579425;5630.1554766785;5630.211770352154;5646.20953066571;5666.9433305666935;5646.74589643466;5682.829515114543;5688.776912963712;5701;5707.258056452655;5090.949090509084;5093.032323858473;5097.694138351692;5101.948980510203;5169.250458683488;4941.160002799533;4955.653104282702;5174.586033117361;5180.974095129519;5214.426413094557;5070.163423035195;5131.358580177478;5133;5143.639945203838;5153.2270159476;5125.692458452505;5125.820596279131;5129.743512824344;5143.09995750744;5152.613553983466;4849.054434385296;5050.873728156789;5052.494750524955;5083.288339632454;5114.156164232897;5475.67145971243;5503.036968530531;5547.944520554824;5571.554275657938;5577.8047768327915;5968.552358573081;5995.310539287994;6043.516518678495;6048.243969503812;6049.092636104576;6064.423879731418;6069.787557435513;6070.8785824283705;6072.149899014142;6079.787207447718;5468.48049435303;5504.834854954318;5574.052411090124;5580.441955804387;5626.381098079208;6155.72299246535;6160.075988601746;6164.260288765377;6164.568480206342;6164.969175154096;5855.063189889597;5925.288965324189;5931.940680593225;5932.762689492372;5973.313068997034;5750.453706897838;5796.391378905154;5899.646021238739;5955.076963070731;6004.969975150184;5984.73068711909;6000.099985002197;6000.699965001732;6023.789167379121;6030.758769649253;5772.307323121252;5776.855578610484;5782.739776710058;5907.852303692442;6023.728932198062;4497.415336006298;4518.638508919278;4521.186186486399;4526.59260666535;4553.908921821545;4012.658923991435;4027.5368332641533;4057.006033521747;4106.67146628269;4122.814473348706;5847.356790753099;5858.912116318259;5860.2967643883;5890.9410905891245;5901.881962360729;6051.485623722033;6100.908486372708;6102.603330783485;6103.511719062464;6122.602030868068;5634.85912852182;5664.20701101846;5854;5854.2389489366105;5857.121431785266;5043.142665746832;5088.6946783193125;5124.282600435942;5139.331886854685;5151.021305951932;6216.6270023797815;6248.812535623939;6255.093011513305;6257.186565746424;6258.968705156536;5829.912551311819;6050.183225264709;6051.909221361771;6056.424639659225;6063.242094738158;6072.301685306246;6153.907691384722;6166.753329866755;6250.687465626791;6258.9374106260175;5968.671723055274;5968.820935371946;5971.104334349882;5973.940260597338;5999.940000600112;6042.395760423922;6047.0627052806885;6049.879002420015;6069.878602427839;6089.48239399656;5679.801206957902;5703.515201207943;5822.70886455684;5862.824115276549;5950.077737950624;5917.940820591739;5918.3489816121055;5921;5924.555658325729;5941.821745347646;5670.121131224667;5840.269966254218;5900.173975643499;5901.085331773582;5908.970455147697;5770.078615339644;5820.447057529528;5876.794312199053;5889.823305300848;6001.129836173643;5848.766049357978;5849.093390524476;5853.64878107315;5854.619449735754;5880.852978675567 15:12:04 INFO - PROCESS | 26485 | |1;ext.html;746.8692978728722;806.4919100966391;820.9425340226185;891.7413949954514;901.3420203251623;31031.534526982112;33459.33081338372;43990.12019759607;44755;49053.50946490534;43208;43471.34792978108;43594;43625.78187109068;43635;7785.2214778521975;9063.68277110303;9203;9207.263418926503;9217.216536594398;7731.149573546905;10556.736081597983;10782.514786834612;10783.5686572537;10786.460676966159;10877.564897404134;10978.231523793338;10980.835287470694;10987.505562249718;10995.890041099567;10788.08301294391;10833.620823271227;10923;10926.180536459718;10950.233483656148;10571.682849514527;10599.62901298451;10613.257072004963;10618.734531636694;10629.574817007311;42359.788201059106;42482.78758606187;42562.36156457655;42573.57426425727;42583.57416425827;3957.248122856648;4016.8393264269394;4158.438610787535;4187.455630768011;4295.720778149442;27323;27376.577728071712;27396.58905116425;27821.582676259874;28299.434011319863;4368.126374725068;4376.737395756265;4387.254166791653;4399.714018588781;4491.101779644085;269846;269929;271104;271835;271980;49099;49153.01693966101;49288.75355623199;49310;49368.75315623399;51903.48096519062;51937.220941685904;51991;51995.74002130041;52001;15634.530964071097;16080.839191608167;16085.67828643444;16091.114988675736;16095.275712592962;12004.51981920731;12237.20458170216;14024.439022438988;14025.438982440588;14045;12970.935145324212;13318.334083295991;13532.25572593497;13720.039597228299;13835.654108647364;5496.5053145216325;5508.14623733322;5581.190727344512;5600.439956004367;5624.831255062355;1009.4801177393648;1011.1102230037667;1013.9898601013896;1014.624588902111;1028.1209565821116;28724.281892952844;28821;28982.565261521093;29356.853215733783;29393.265168370534;1083.3391631105178;1084.1110289562482;1094.7427354571669;1103.3710784852717;1115.5259014918627;14511.201883896276;15193.62015949588;15430.614234644;15511.457099001707;15566.766498502531;322037;324672;324844;326241;327053;52942.735286324096;53199.20201196985;53319.73340133274;53403.73298133562;53422.73288633532;1450.2821103553808;1483.406637345049;1485.7697056956406;1513.7653663682147;1519.5289460267352;28212.85893570519;29386;29528.409431810815;30048;30215.546766798954;40366;40381.394279086424;40410.39384409295;40520.79739601283;40641.1871762569;349316;351714;351807;352440;352917;26023.73976260213;26534.203973880816;27410.588841167;31441.528377073904;31985.88049418259;39081.80459097686;39699.40450893297;39856.800715996236;40203.39694904637;40530.39204411877;30835.537466938462;30911.2272193197;31073;31261.687383126788;31823.681763182998;30693.23266918345;30766.692333076382;30774.230644234074;30920.53619195669;31564.210894727814;34572.82713586416;35208;35346.293074138885;35564.644353556134;35685;29560.113196604136;29943.85028074846;30257.848710756305;30467.085987420414;31167.844160779052;26229;26390.472190556466;26448.735512644627;26495.60256596191;26966.191014268818 15:12:04 INFO - PROCESS | 26485 | |2;jquery.html;466.4123204762001;468.58296116456347;469.2679420104635;479.5132940065832;480.822095824545;19322.420327390184;20535.38393848183;21459;21862;22045.11819527221;22288;22428.551428971412;22837.4290642734;22883.885580572114;23172.188973385935;6322.7470901164015;6496.902546461807;6607.834804129911;6763.391294783469;6815.216250131224;6681.432078273328;6803.931960680379;6807.965960170192;6966.233714291424;7260.382867456273;7380.114386273654;7380.667869945865;7394.334509894108;7397.963010184942;7398.7410440634585;7316.122065352165;7355.0070740450155;7597.962010189969;7614.581198034106;7626.771196864048;7400.407967362598;7402.777916662509;7451.888221676679;7464.70141194357;7474.103107627066;25144;25677.358066048313;25745.871270643525;25753.227403177934;26025.869870650524;3006.4438078955463;3096.427160975227;3131.749460043191;3187.0916788715103;3208.1819136120375;15900.681986360209;16460.753088703677;16514.917425412918;16680.49958501247;16708.6658266834;2204.2946257197705;2221.6334304839684;2264.784845439681;2303.5968705476475;2309.919152829643;118829;122274.38862805629;122318;122661;122754;18315;18629.789063711087;18807.341743038927;18898.433047008613;19113.426597202106;32596.83701581477;32674;32719.67280327214;32763.180920477178;32980.67019329824;10741.462926853626;10797;10861;10861.511231994578;11107.389093599768;9113.498757568264;9133.223675987483;9137.177654011039;9354.812903742024;9376.48429336393;9054.456732596065;9287.953560232156;9426.821647294088;9483.146516813382;9561.139497445125;3682.502862113634;3853.8013448837396;3880.980595097063;3910.4720862683744;3964.4647972523917;958.4680502321139;958.6357184270072;963.5375019990444;969.1713584884963;970.4953424219508;19951.800481994993;21102.577948440947;21435.67846482304;21625;21747;979.4221409368364;983.7408117609472;990.0891180114169;991.791723738023;997.8054279415444;11197.66407007791;11632.127590430751;11796.46915888787;11853.822192667118;11864.22882512634;649351;663397;671433;671489;680572;95845;109319.45340273248;110378;121474;122114;1327.9177470361624;1344.88568471681;1393.4147657983672;1393.9651508712363;1396.1064918451996;19202.903985479985;19347.322843700404;19682.8031719681;19703.507412314233;19742.40772776819;38117.61882381252;38312.80843595764;46843.531564683915;53990.1103461377;54321;72864;72929.63535182503;73760;74811.25188748042;74882;12250.081243906565;12546.247225166519;12763.744725105631;12807.871921280668;12820.358982050673;13854.930725346308;13860.168389896235;14254.287285635884;14446.927765361106;14610.634734131732;12311.19977201479;12608.117431779687;12696.238225706487;12698.365081745691;12840.743185136058;9952.800943981225;10065.993400660169;10967.670969870916;11622.360770157722;11681.415929203336;12613.36933153357;12847.164934279243;12945.352732363532;12972.416241268977;13064.608061758163;12104.576339828063;12272.509099635914;12654.493820247088;12792.74414511686;13008.804867927178;12484.313362765135;12667.896365390538;12801.679958001125;12831.294278814756;13039.674008149872 15:12:04 INFO - PROCESS | 26485 | |3;mootools.html;1446.2334962469895;1547.118142658685;1563.155895816259;1564.7027064857668;1573.6695293988262;9445.716628501148;11523.596674116408;11907.345096019715;11914.582989595367;11918.761624767521;11583.710407239823;11686.415679216027;11691.298522088659;11702.590409335675;11708.297502149855;1990.8805471671674;2066.132224465728;2402.6876506054186;2433;2433.8904749286235;2396.9760302397012;2463.3718401807514;2471.6045432730766;2476.8018558515278;2479.4917042006346;2372.2527403867725;2399.3881560202126;2404.723456802473;2405.1581946318825;2408.506256217471;1999.2103119267954;2073.6060148571723;2329.592321343758;2336.5794157051723;2342.0514691550034;2356.552255071531;2441.816863735227;2459.114718701284;2468.8642124683133;2471.7157526884457;11577.421128943519;11654.834516548362;11675.649730508097;11676.532938682443;11683.299002059819;5766.855828604276;5911.29064512257;5918.467337939563;5918.763249470016;5921;3543.8228088595465;3557.875474358385;3640.4357324614725;3702.796346200958;3714.23858109087;1339;1344.744498545283;1382.7234553089322;1395.3073246196907;1427.193635595885;17405.129743512774;17447.38934137299;17472.65054698899;17507.73738393925;17541.73687394703;4747.548982846624;4998.350214472095;5042.4957504250015;5057.266696329012;5122.410922743932;13957.651058723613;14006.43974241019;14031.579052628438;14049.227292498806;14054.086484378482;6015.6691381974415;6049.818505444756;6050.909236361458;6053.697315134314;6055.273367195965;5313.973430132824;5447.264619276347;5461.180822876521;5483.616146869761;5493.2034854945805;4303.784810759449;4380.408644832907;4399.956000440018;4453.888652783707;4454.643628509712;1119.4346854838254;1131.3607811586428;1139.2480962564669;1141.1099342512853;1147.391882302381;972.2610815779917;993.7267251505893;1007.5163921317809;1011.8836333821557;1012.5494155101056;6495.8700825983215;6575.967120164369;6578.703958321887;6663.833404164935;6712.832179195461;1018.4042335233971;1029.1149611334247;1041.037040737315;1041.2971244410144;1048.7692707604315;4407.471103467605;4415.337699345124;4417.668674849399;4433.091216300651;4438.889027774267;14016.579502614937;14482.782758258634;14602.780958285633;14676.55970320892;14678.41286348534;14003.579892603238;14303.356348964322;14449.49426770058;14473.348699308557;14502.274886255856;10229.693109206735;10232.795344093076;10266.640667576748;10360.378377297386;10374.844377334346;6268.122462855113;6297.559170857996;6304.747810087545;6357.14178585885;6440.323766004502;12213.145079844326;12619.810702839648;12648.114631975674;12750.107492475438;12762.29807360604;12916.160449571124;12988.025898057873;12990.675233119247;13014.674633134247;13185.208887466782;9705.078017588317;9815.803683926424;9866.703998880044;9895.158911492605;9915.702528924145;10672.8932710675;10769.892301076889;10852;10872.293300935415;10878.292910960763;9081.863772043293;9224.677136300197;9241.58412871409;9346.532673366168;9467.479288639192;8816.471011739317;8900.287976961958;8950.104989500998;9198.816023679356;9245.075492450702;7889.881651775343;7958.84082318362;8066.7579972600915;8122.918770812453;8170.346372290083;5998.7300571473515;6041.24484439445;6086.087086936995;6214.4407003369915;6227.595206311757;6327.240731112296;6344.936550634435;6348.206474190726;6431.839204019937;6495.512836537184 15:12:04 INFO - PROCESS | 26485 | |4;prototype.html;417.80989649709386;439.5538528393681;439.6482813749001;440.8302803420682;442.8472177098902;5280.7887684492625;5346.144616861303;5382.3541175059;5384.6769193848295;5401.91897121543;5030.440563425341;5275.36695596528;5308.256844041827;5309.73451327433;5327.706976116312;4350.825966961318;4400.493943196533;4400.713953593006;4415.889602759925;4429.756363400012;4409.911801763963;4437.4009508716235;4448.132614140244;4456.955430445686;4469.44131983502;4339.067100573376;4382.671299652523;4395.922998865282;4427.402300689414;4441.955580444186;4346.195953748566;4374.278244089722;4389.319655453378;4390.648748100144;4403.405540252057;4114.547399786021;4128.938065929014;4132.855350062733;4136.400221967828;4142.565030671766;5202;5213.374395072616;5219.399769026534;5240.973795131038;5259.0796610593;5040.798368065273;5115.718635475047;5116.2069879168785;5156.484351564851;5161.483851614846;5510.531604813596;5536.916946245809;5566.415526369754;5574.191742197399;5575.52248654107;4514.7291162530355;4606.355110284563;4622.653301002405;4625.861224163281;4673.813047478096;5510.889782204334;5550.335838797399;5622.690752008638;5802.390748971339;5834;4712.31671407644;4733.0654993904;4736.668433209711;4743.359646447754;4766.95233047672;4830.420349558005;4831.444383895827;4835.081334546495;4835.758212089381;4838.0082083172865;4792.496787837227;4824.975875120602;5117.41149767774;5132.255822905732;5168.664036837594;5511;5548.91676624851;5552.888942221214;5553.139263414178;5571.554275657938;1652.355581323285;1662.9916850415912;1675.5308513616208;1678.3118921242233;1681.4619321817145;1235.6972541727155;1301.1997621462733;1310.1484035377082;1315.9407826647823;1331.8202042724301;5335.506058303681;5513.9448605513435;5532.585056120807;5553.861153471195;5570.498655121059;5702.714864256854;5747.655140691572;5754.568407369464;5785.971070144622;5787.565932554991;5517.779288828482;5535.308086489189;5543.833684989457;5571.777128914798;5594.300712410949;2890.869910854017;3028.424599326121;3052.168698780714;3055.816651000947;3057.4649436348996;5891.909996650634;5904.261967254093;5936.614120082181;5998;6055.485283750931;5670.5463562914865;5714.000099964979;5717.285339332583;5926.377730338252;5939.346671866091;5837.211976383134;5904.608633503524;5914.556408269397;5922.318933322636;5932.911006334822;5676.829695109153;5816.680082595329;5823.359430462561;5832.366937257405;5833.329167145663;5358.356997160366;5663.405342438985;5682.204491371129;5689.77240910359;5702.486776190163;5654.558087687613;5716.942830571641;5903.616264942765;5961.612495187799;6015.608985415934;4753.429588449409;4765.237561990065;4831.130396528518;4844.588210002189;4851.757412129309;5444.047291724011;5707.942920570741;5791.478766910998;5852.507610559451;5877.500412464818;5480.753366098455;5497.202905578589;5512.972435137799;5571.749271282721;5608.242887210175;5563.217012431585;5563.58273129525;5570.599226611094;5581.525570326406;5594.86012849682;5307.6284660073425;5540.473655002768;5570.832875013756;5574.331080270394;5581.944180558305;5610.214569960127;5620.775168993358;5647.350554686263;5652.293463317085;5665.603407761417;5459.727013649381;5524.508382736558;5549.389567147691;5590.8043218487155;5615.775368985358 15:12:04 INFO - PROCESS | 26485 | |5;yui.html;827.4290739389825;891.656712165816;899.5142622983585;909.5043201455206;929.6327950459569;16808.747868781964;17952.64094718108;18089.638207235846;18106.818931810678;18198.72701909471;16072.276747546359;16368.427105051296;16417.507474775746;16424.507264782045;16479.67040659186;871.895372555291;896.7668406214366;902.1835239108617;908.2416182487631;923.4690053219425;903.4263242840777;903.4850135422812;907.3240435875282;909.122696597783;909.2589539525266;961.2742379503466;963.9277054220927;964.5900492290783;964.9903500964971;966.0870477398826;962.5476026267643;962.7496850818768;963.2390411574883;963.330485312708;963.9132478076972;700.1702981966336;702.3468174597622;703.0684343245221;703.5778532880282;703.725547036651;18065.187066582035;18066.36767713124;18102;18515.074246287633;18737;15917.602059948478;15989.680206395924;16196.514104576881;16405.753913691304;16793.07638079905;12956.157849739737;13166.934165329112;13306.573502812184;13633.386497607631;13716.931415342859;4112.712110152291;4125.133721918401;4132.938005929914;4212.326027835532;4214.473190851144;88481.55759221227;89875.55062224713;89938.99999999935;91370;91553.54223228841;12446.315452650191;12504.81242781359;12546.749065018648;12564.120511564102;12742;23605.1738189166;24356.634650480257;24636;24684.38289042788;24777.62833557498;9296.488693121808;9469.810603787886;9769.706908792748;10038.899611003942;10103.84844227337;5044.066847633199;5258.474152584711;5315.548178404879;5336.17289320156;5411.485908838654;3797.5063241778394;3924.843006279717;3929.332013557702;4160.812763425653;4323.718958267703;722.794003708951;723.8588475247276;725.7169703815516;733.5012191709689;734.4931996922106;595.7795615622248;607.3713706313944;608.0878681977034;647.4334956912701;827.462149602751;11131.773645270978;11244.66266012021;11267.535419803171;12011.099167562468;12200.938995305145;668.1014036121409;669.4677731203705;684.9863002739917;718.9964050179716;766.172533663642;5505.476979686923;5696.321600079228;5726.885462290731;5738;5788.334341550775;83692.58153709314;84720.15279847123;85953.5702321497;87877.56061219652;89258.55370723105;43479.782601086794;50457;67935.32064679389;71431.28568714246;71450.64274678593;51418.228726569134;61184;61734.69132654308;61882;63400.999999999076;11135.276207046516;11508.712282193012;11578.76842463163;11585.88414115848;11862.584809531625;41395;42048.57951420569;42240;42762;43633.56366436422;47204.29193562168;47703.28445073257;48022;48284.75857620689;49553.752231240054;13599.59201223965;13628;13633.522826701017;13847.376868040761;14434.566962991126;16668.833311666727;16716.66566668684;16951.915240423717;17051.147442628066;17131.314747409964;15034.398624054915;15088.471903483325;15371.923140384226;15691.607709807346;15838.208089595704;14303.21332326732;14629.487967921072;14690.55928322152;15002.924985375439;15631.452899148475;14979.176145312113;15011.42259642312;15253.313600888208;15469.922650386676;15828.52514424569;11712.648620541397;11874.82187767167;12341;12488.664334758374;12589.685257868627;11266.718332041764;11923.642290731292;12349.75300494003;12441.60829573512;12650.493980240688 15:12:04 INFO - PROCESS | 26485 | __end_tp_report 15:12:04 INFO - PROCESS | 26485 | __start_cc_report 15:12:04 INFO - PROCESS | 26485 | _x_x_mozilla_cycle_collect,1426 15:12:04 INFO - PROCESS | 26485 | __end_cc_report 15:12:04 INFO - PROCESS | 26485 | __startTimestamp1491603124928__endTimestamp 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | ------- Summary: start ------- 15:12:04 INFO - PROCESS | 26485 | Number of tests: 6 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | [#0] dojo.html Cycles:165 Average:5588.54 Median:5849.09 stddev:632.42 (10.8%) stddev-sans-first:609.36 15:12:04 INFO - PROCESS | 26485 | Values: 3344.2 3376.7 3382.5 3416.2 3433.2 5571.6 5630.2 5630.2 5646.2 5666.9 5646.7 5682.8 5688.8 5701.0 5707.3 5090.9 5093.0 5097.7 5101.9 5169.3 4941.2 4955.7 5174.6 5181.0 5214.4 5070.2 5131.4 5133.0 5143.6 5153.2 5125.7 5125.8 5129.7 5143.1 5152.6 4849.1 5050.9 5052.5 5083.3 5114.2 5475.7 5503.0 5547.9 5571.6 5577.8 5968.6 5995.3 6043.5 6048.2 6049.1 6064.4 6069.8 6070.9 6072.1 6079.8 5468.5 5504.8 5574.1 5580.4 5626.4 6155.7 6160.1 6164.3 6164.6 6165.0 5855.1 5925.3 5931.9 5932.8 5973.3 5750.5 5796.4 5899.6 5955.1 6005.0 5984.7 6000.1 6000.7 6023.8 6030.8 5772.3 5776.9 5782.7 5907.9 6023.7 4497.4 4518.6 4521.2 4526.6 4553.9 4012.7 4027.5 4057.0 4106.7 4122.8 5847.4 5858.9 5860.3 5890.9 5901.9 6051.5 6100.9 6102.6 6103.5 6122.6 5634.9 5664.2 5854.0 5854.2 5857.1 5043.1 5088.7 5124.3 5139.3 5151.0 6216.6 6248.8 6255.1 6257.2 6259.0 5829.9 6050.2 6051.9 6056.4 6063.2 6072.3 6153.9 6166.8 6250.7 6258.9 5968.7 5968.8 5971.1 5973.9 5999.9 6042.4 6047.1 6049.9 6069.9 6089.5 5679.8 5703.5 5822.7 5862.8 5950.1 5917.9 5918.3 5921.0 5924.6 5941.8 5670.1 5840.3 5900.2 5901.1 5909.0 5770.1 5820.4 5876.8 5889.8 6001.1 5848.8 5849.1 5853.6 5854.6 5880.9 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | [#1] ext.html Cycles:180 Average:47384.95 Median:30915.88 stddev:83225.20 (269.2%) stddev-sans-first:83385.00 15:12:04 INFO - PROCESS | 26485 | Values: 746.9 806.5 820.9 891.7 901.3 31031.5 33459.3 43990.1 44755.0 49053.5 43208.0 43471.3 43594.0 43625.8 43635.0 7785.2 9063.7 9203.0 9207.3 9217.2 7731.1 10556.7 10782.5 10783.6 10786.5 10877.6 10978.2 10980.8 10987.5 10995.9 10788.1 10833.6 10923.0 10926.2 10950.2 10571.7 10599.6 10613.3 10618.7 10629.6 42359.8 42482.8 42562.4 42573.6 42583.6 3957.2 4016.8 4158.4 4187.5 4295.7 27323.0 27376.6 27396.6 27821.6 28299.4 4368.1 4376.7 4387.3 4399.7 4491.1 269846.0 269929.0 271104.0 271835.0 271980.0 49099.0 49153.0 49288.8 49310.0 49368.8 51903.5 51937.2 51991.0 51995.7 52001.0 15634.5 16080.8 16085.7 16091.1 16095.3 12004.5 12237.2 14024.4 14025.4 14045.0 12970.9 13318.3 13532.3 13720.0 13835.7 5496.5 5508.1 5581.2 5600.4 5624.8 1009.5 1011.1 1014.0 1014.6 1028.1 28724.3 28821.0 28982.6 29356.9 29393.3 1083.3 1084.1 1094.7 1103.4 1115.5 14511.2 15193.6 15430.6 15511.5 15566.8 322037.0 324672.0 324844.0 326241.0 327053.0 52942.7 53199.2 53319.7 53403.7 53422.7 1450.3 1483.4 1485.8 1513.8 1519.5 28212.9 29386.0 29528.4 30048.0 30215.5 40366.0 40381.4 40410.4 40520.8 40641.2 349316.0 351714.0 351807.0 352440.0 352917.0 26023.7 26534.2 27410.6 31441.5 31985.9 39081.8 39699.4 39856.8 40203.4 40530.4 30835.5 30911.2 31073.0 31261.7 31823.7 30693.2 30766.7 30774.2 30920.5 31564.2 34572.8 35208.0 35346.3 35564.6 35685.0 29560.1 29943.9 30257.8 30467.1 31167.8 26229.0 26390.5 26448.7 26495.6 26966.2 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | [#2] jquery.html Cycles:180 Average:38268.45 Median:2306.76 stddev:110063.05 (4771.3%) stddev-sans-first:110335.20 15:12:04 INFO - PROCESS | 26485 | Values: 466.4 468.6 469.3 479.5 480.8 19322.4 20535.4 21459.0 21862.0 22045.1 22288.0 22428.6 22837.4 22883.9 23172.2 6322.7 6496.9 6607.8 6763.4 6815.2 6681.4 6803.9 6808.0 6966.2 7260.4 7380.1 7380.7 7394.3 7398.0 7398.7 7316.1 7355.0 7598.0 7614.6 7626.8 7400.4 7402.8 7451.9 7464.7 7474.1 25144.0 25677.4 25745.9 25753.2 26025.9 3006.4 3096.4 3131.7 3187.1 3208.2 15900.7 16460.8 16514.9 16680.5 16708.7 2204.3 2221.6 2264.8 2303.6 2309.9 118829.0 122274.4 122318.0 122661.0 122754.0 18315.0 18629.8 18807.3 18898.4 19113.4 32596.8 32674.0 32719.7 32763.2 32980.7 10741.5 10797.0 10861.0 10861.5 11107.4 9113.5 9133.2 9137.2 9354.8 9376.5 9054.5 9288.0 9426.8 9483.1 9561.1 3682.5 3853.8 3881.0 3910.5 3964.5 958.5 958.6 963.5 969.2 970.5 19951.8 21102.6 21435.7 21625.0 21747.0 979.4 983.7 990.1 991.8 997.8 11197.7 11632.1 11796.5 11853.8 11864.2 649351.0 663397.0 671433.0 671489.0 680572.0 95845.0 109319.5 110378.0 121474.0 122114.0 1327.9 1344.9 1393.4 1394.0 1396.1 19202.9 19347.3 19682.8 19703.5 19742.4 38117.6 38312.8 46843.5 53990.1 54321.0 72864.0 72929.6 73760.0 74811.3 74882.0 12250.1 12546.2 12763.7 12807.9 12820.4 13854.9 13860.2 14254.3 14446.9 14610.6 12311.2 12608.1 12696.2 12698.4 12840.7 9952.8 10066.0 10967.7 11622.4 11681.4 12613.4 12847.2 12945.4 12972.4 13064.6 12104.6 12272.5 12654.5 12792.7 13008.8 12484.3 12667.9 12801.7 12831.3 13039.7 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | [#3] mootools.html Cycles:180 Average:7111.77 Median:2406.83 stddev:4589.36 (190.7%) stddev-sans-first:4582.49 15:12:04 INFO - PROCESS | 26485 | Values: 1446.2 1547.1 1563.2 1564.7 1573.7 9445.7 11523.6 11907.3 11914.6 11918.8 11583.7 11686.4 11691.3 11702.6 11708.3 1990.9 2066.1 2402.7 2433.0 2433.9 2397.0 2463.4 2471.6 2476.8 2479.5 2372.3 2399.4 2404.7 2405.2 2408.5 1999.2 2073.6 2329.6 2336.6 2342.1 2356.6 2441.8 2459.1 2468.9 2471.7 11577.4 11654.8 11675.6 11676.5 11683.3 5766.9 5911.3 5918.5 5918.8 5921.0 3543.8 3557.9 3640.4 3702.8 3714.2 1339.0 1344.7 1382.7 1395.3 1427.2 17405.1 17447.4 17472.7 17507.7 17541.7 4747.5 4998.4 5042.5 5057.3 5122.4 13957.7 14006.4 14031.6 14049.2 14054.1 6015.7 6049.8 6050.9 6053.7 6055.3 5314.0 5447.3 5461.2 5483.6 5493.2 4303.8 4380.4 4400.0 4453.9 4454.6 1119.4 1131.4 1139.2 1141.1 1147.4 972.3 993.7 1007.5 1011.9 1012.5 6495.9 6576.0 6578.7 6663.8 6712.8 1018.4 1029.1 1041.0 1041.3 1048.8 4407.5 4415.3 4417.7 4433.1 4438.9 14016.6 14482.8 14602.8 14676.6 14678.4 14003.6 14303.4 14449.5 14473.3 14502.3 10229.7 10232.8 10266.6 10360.4 10374.8 6268.1 6297.6 6304.7 6357.1 6440.3 12213.1 12619.8 12648.1 12750.1 12762.3 12916.2 12988.0 12990.7 13014.7 13185.2 9705.1 9815.8 9866.7 9895.2 9915.7 10672.9 10769.9 10852.0 10872.3 10878.3 9081.9 9224.7 9241.6 9346.5 9467.5 8816.5 8900.3 8950.1 9198.8 9245.1 7889.9 7958.8 8066.8 8122.9 8170.3 5998.7 6041.2 6086.1 6214.4 6227.6 6327.2 6344.9 6348.2 6431.8 6495.5 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | [#4] prototype.html Cycles:180 Average:4865.74 Median:5383.52 stddev:1296.75 (24.1%) stddev-sans-first:1256.68 15:12:04 INFO - PROCESS | 26485 | Values: 417.8 439.6 439.6 440.8 442.8 5280.8 5346.1 5382.4 5384.7 5401.9 5030.4 5275.4 5308.3 5309.7 5327.7 4350.8 4400.5 4400.7 4415.9 4429.8 4409.9 4437.4 4448.1 4457.0 4469.4 4339.1 4382.7 4395.9 4427.4 4442.0 4346.2 4374.3 4389.3 4390.6 4403.4 4114.5 4128.9 4132.9 4136.4 4142.6 5202.0 5213.4 5219.4 5241.0 5259.1 5040.8 5115.7 5116.2 5156.5 5161.5 5510.5 5536.9 5566.4 5574.2 5575.5 4514.7 4606.4 4622.7 4625.9 4673.8 5510.9 5550.3 5622.7 5802.4 5834.0 4712.3 4733.1 4736.7 4743.4 4767.0 4830.4 4831.4 4835.1 4835.8 4838.0 4792.5 4825.0 5117.4 5132.3 5168.7 5511.0 5548.9 5552.9 5553.1 5571.6 1652.4 1663.0 1675.5 1678.3 1681.5 1235.7 1301.2 1310.1 1315.9 1331.8 5335.5 5513.9 5532.6 5553.9 5570.5 5702.7 5747.7 5754.6 5786.0 5787.6 5517.8 5535.3 5543.8 5571.8 5594.3 2890.9 3028.4 3052.2 3055.8 3057.5 5891.9 5904.3 5936.6 5998.0 6055.5 5670.5 5714.0 5717.3 5926.4 5939.3 5837.2 5904.6 5914.6 5922.3 5932.9 5676.8 5816.7 5823.4 5832.4 5833.3 5358.4 5663.4 5682.2 5689.8 5702.5 5654.6 5716.9 5903.6 5961.6 6015.6 4753.4 4765.2 4831.1 4844.6 4851.8 5444.0 5707.9 5791.5 5852.5 5877.5 5480.8 5497.2 5513.0 5571.7 5608.2 5563.2 5563.6 5570.6 5581.5 5594.9 5307.6 5540.5 5570.8 5574.3 5581.9 5610.2 5620.8 5647.4 5652.3 5665.6 5459.7 5524.5 5549.4 5590.8 5615.8 15:12:04 INFO - PROCESS | 26485 | 15:12:04 INFO - PROCESS | 26485 | [#5] yui.html Cycles:180 Average:18530.32 Median:23130.45 stddev:22953.45 (99.2%) stddev-sans-first:22979.35 15:12:04 INFO - PROCESS | 26485 | Values: 827.4 891.7 899.5 909.5 929.6 16808.7 17952.6 18089.6 18106.8 18198.7 16072.3 16368.4 16417.5 16424.5 16479.7 871.9 896.8 902.2 908.2 923.5 903.4 903.5 907.3 909.1 909.3 961.3 963.9 964.6 965.0 966.1 962.5 962.7 963.2 963.3 963.9 700.2 702.3 703.1 703.6 703.7 18065.2 18066.4 18102.0 18515.1 18737.0 15917.6 15989.7 16196.5 16405.8 16793.1 12956.2 13166.9 13306.6 13633.4 13716.9 4112.7 4125.1 4132.9 4212.3 4214.5 88481.6 89875.6 89939.0 91370.0 91553.5 12446.3 12504.8 12546.7 12564.1 12742.0 23605.2 24356.6 24636.0 24684.4 24777.6 9296.5 9469.8 9769.7 10038.9 10103.8 5044.1 5258.5 5315.5 5336.2 5411.5 3797.5 3924.8 3929.3 4160.8 4323.7 722.8 723.9 725.7 733.5 734.5 595.8 607.4 608.1 647.4 827.5 11131.8 11244.7 11267.5 12011.1 12200.9 668.1 669.5 685.0 719.0 766.2 5505.5 5696.3 5726.9 5738.0 5788.3 83692.6 84720.2 85953.6 87877.6 89258.6 43479.8 50457.0 67935.3 71431.3 71450.6 51418.2 61184.0 61734.7 61882.0 63401.0 11135.3 11508.7 11578.8 11585.9 11862.6 41395.0 42048.6 42240.0 42762.0 43633.6 47204.3 47703.3 48022.0 48284.8 49553.8 13599.6 13628.0 13633.5 13847.4 14434.6 16668.8 16716.7 16951.9 17051.1 17131.3 15034.4 15088.5 15371.9 15691.6 15838.2 14303.2 14629.5 14690.6 15002.9 15631.5 14979.2 15011.4 15253.3 15469.9 15828.5 11712.6 11874.8 12341.0 12488.7 12589.7 11266.7 11923.6 12349.8 12441.6 12650.5 15:12:04 INFO - PROCESS | 26485 | -------- Summary: end -------- 15:12:04 INFO - PROCESS | 26485 | 15:12:05 INFO - TEST-INFO | 26485: exit 0 15:12:05 INFO - TEST-OK | dromaeo_css | took 1115121ms 15:12:05 INFO - TEST-START | kraken 15:12:05 INFO - operating with platform_type : linux_ 15:12:05 INFO - Initialising browser for kraken test... 15:12:05 INFO - TEST-INFO | started process 26805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9XnuTf/profile http://localhost:41745/getInfo.html) 15:12:09 INFO - PROCESS | 26805 | __metrics Screen width/height:1600/1200 15:12:09 INFO - PROCESS | 26805 | colorDepth:24 15:12:09 INFO - PROCESS | 26805 | Browser inner width/height: 1024/697 15:12:09 INFO - PROCESS | 26805 | __metrics 15:12:10 INFO - PROCESS | 26805 | 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" 15:12:10 INFO - PROCESS | 26805 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:12:10 INFO - PROCESS | 26805 | console.error: PushService: 15:12:10 INFO - PROCESS | 26805 | stateChangeProcessEnqueue: Error transitioning state 15:12:10 INFO - PROCESS | 26805 | UnknownError 15:12:11 INFO - TEST-INFO | 26805: exit 0 15:12:11 INFO - Browser initialized. 15:12:11 INFO - Running cycle 1/1 for kraken test... 15:12:11 INFO - TEST-INFO | started process 26900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9XnuTf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:23 INFO - PROCESS | 26900 | RSS: Main: 139546624 15:12:23 INFO - PROCESS | 26900 | 15:12:25 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41745/tests/kraken/driver.html?testName=audio-beat-detection) 15:12:26 INFO - PROCESS | 26900 | RSS: Main: 183283712 15:12:26 INFO - PROCESS | 26900 | 15:12:29 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41745/tests/kraken/driver.html?testName=audio-dft) 15:12:29 INFO - PROCESS | 26900 | RSS: Main: 174387200 15:12:29 INFO - PROCESS | 26900 | 15:12:32 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41745/tests/kraken/driver.html?testName=audio-fft) 15:12:33 INFO - PROCESS | 26900 | RSS: Main: 266268672 15:12:33 INFO - PROCESS | 26900 | 15:12:35 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41745/tests/kraken/driver.html?testName=audio-oscillator) 15:12:35 INFO - PROCESS | 26900 | RSS: Main: 172285952 15:12:35 INFO - PROCESS | 26900 | 15:12:37 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41745/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:12:38 INFO - PROCESS | 26900 | RSS: Main: 175026176 15:12:38 INFO - PROCESS | 26900 | 15:12:41 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41745/tests/kraken/driver.html?testName=imaging-darkroom) 15:12:42 INFO - PROCESS | 26900 | RSS: Main: 213725184 15:12:42 INFO - PROCESS | 26900 | 15:12:45 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41745/tests/kraken/driver.html?testName=imaging-desaturate) 15:12:46 INFO - PROCESS | 26900 | RSS: Main: 212340736 15:12:46 INFO - PROCESS | 26900 | 15:12:48 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41745/tests/kraken/driver.html?testName=json-parse-financial) 15:12:49 INFO - PROCESS | 26900 | RSS: Main: 210964480 15:12:49 INFO - PROCESS | 26900 | 15:12:50 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41745/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:12:51 INFO - PROCESS | 26900 | RSS: Main: 173420544 15:12:51 INFO - PROCESS | 26900 | 15:12:53 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:12:53 INFO - PROCESS | 26900 | RSS: Main: 180080640 15:12:53 INFO - PROCESS | 26900 | 15:12:55 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:12:56 INFO - PROCESS | 26900 | RSS: Main: 190115840 15:12:56 INFO - PROCESS | 26900 | 15:12:58 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:12:59 INFO - PROCESS | 26900 | RSS: Main: 198582272 15:12:59 INFO - PROCESS | 26900 | 15:13:02 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:13:02 INFO - PROCESS | 26900 | RSS: Main: 195006464 15:13:02 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | Cycle 1(1): loaded http://localhost:41745/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41745/tests/kraken/driver.html?testName=ai-astar) 15:13:04 INFO - PROCESS | 26900 | RSS: Main: 246480896 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | __start_tp_report 15:13:04 INFO - PROCESS | 26900 | _x_x_mozilla_page_load 15:13:04 INFO - PROCESS | 26900 | _x_x_mozilla_page_load_details 15:13:04 INFO - PROCESS | 26900 | |i|pagename|runs| 15:13:04 INFO - PROCESS | 26900 | |0;ai-astar;103;113;113;112;113;112;113;114;112;112 15:13:04 INFO - PROCESS | 26900 | |1;audio-beat-detection;141;115;122;150;122;121;116;116;136;115 15:13:04 INFO - PROCESS | 26900 | |2;audio-dft;161;162;162;171;162;162;161;162;161;162 15:13:04 INFO - PROCESS | 26900 | |3;audio-fft;74;75;75;115;75;77;76;76;91;75 15:13:04 INFO - PROCESS | 26900 | |4;audio-oscillator;83;82;111;83;82;82;101;84;82;110 15:13:04 INFO - PROCESS | 26900 | |5;imaging-gaussian-blur;113;113;114;113;113;113;113;113;113;113 15:13:04 INFO - PROCESS | 26900 | |6;imaging-darkroom;147;146;146;146;147;146;147;147;148;146 15:13:04 INFO - PROCESS | 26900 | |7;imaging-desaturate;94;94;93;94;94;93;94;95;93;94 15:13:04 INFO - PROCESS | 26900 | |8;json-parse-financial;54;55;54;53;54;54;53;54;54;55 15:13:04 INFO - PROCESS | 26900 | |9;json-stringify-tinderbox;53;54;54;54;54;53;53;54;54;54 15:13:04 INFO - PROCESS | 26900 | |10;stanford-crypto-aes;79;84;76;78;81;75;82;74;78;79 15:13:04 INFO - PROCESS | 26900 | |11;stanford-crypto-ccm;124;140;122;121;126;119;132;121;159;134 15:13:04 INFO - PROCESS | 26900 | |12;stanford-crypto-pbkdf2;160;162;161;163;165;160;162;163;160;159 15:13:04 INFO - PROCESS | 26900 | |13;stanford-crypto-sha256-iterative;60;61;60;60;60;59;59;59;58;59 15:13:04 INFO - PROCESS | 26900 | __end_tp_report 15:13:04 INFO - PROCESS | 26900 | __start_cc_report 15:13:04 INFO - PROCESS | 26900 | _x_x_mozilla_cycle_collect,1674 15:13:04 INFO - PROCESS | 26900 | __end_cc_report 15:13:04 INFO - PROCESS | 26900 | __startTimestamp1491603184772__endTimestamp 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | ------- Summary: start ------- 15:13:04 INFO - PROCESS | 26900 | Number of tests: 14 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.13 (2.8%) stddev-sans-first:0.71 15:13:04 INFO - PROCESS | 26900 | Values: 103.0 113.0 113.0 112.0 113.0 112.0 113.0 114.0 112.0 112.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:122.00 stddev:12.46 (10.2%) stddev-sans-first:11.86 15:13:04 INFO - PROCESS | 26900 | Values: 141.0 115.0 122.0 150.0 122.0 121.0 116.0 116.0 136.0 115.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#2] audio-dft Cycles:10 Average:162.60 Median:162.00 stddev:2.99 (1.8%) stddev-sans-first:3.11 15:13:04 INFO - PROCESS | 26900 | Values: 161.0 162.0 162.0 171.0 162.0 162.0 161.0 162.0 161.0 162.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.50 stddev:12.97 (17.2%) stddev-sans-first:13.52 15:13:04 INFO - PROCESS | 26900 | Values: 74.0 75.0 75.0 115.0 75.0 77.0 76.0 76.0 91.0 75.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:82.00 stddev:12.26 (14.9%) stddev-sans-first:12.74 15:13:04 INFO - PROCESS | 26900 | Values: 83.0 82.0 111.0 83.0 82.0 82.0 101.0 84.0 82.0 110.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:13:04 INFO - PROCESS | 26900 | Values: 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 15:13:04 INFO - PROCESS | 26900 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 147.0 147.0 148.0 146.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 15:13:04 INFO - PROCESS | 26900 | Values: 94.0 94.0 93.0 94.0 94.0 93.0 94.0 95.0 93.0 94.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 15:13:04 INFO - PROCESS | 26900 | Values: 54.0 55.0 54.0 53.0 54.0 54.0 53.0 54.0 54.0 55.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 15:13:04 INFO - PROCESS | 26900 | Values: 53.0 54.0 54.0 54.0 54.0 53.0 53.0 54.0 54.0 54.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#10] stanford-crypto-aes Cycles:10 Average:78.60 Median:79.00 stddev:3.13 (4.0%) stddev-sans-first:3.32 15:13:04 INFO - PROCESS | 26900 | Values: 79.0 84.0 76.0 78.0 81.0 75.0 82.0 74.0 78.0 79.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#11] stanford-crypto-ccm Cycles:10 Average:129.80 Median:129.00 stddev:12.29 (9.5%) stddev-sans-first:12.86 15:13:04 INFO - PROCESS | 26900 | Values: 124.0 140.0 122.0 121.0 126.0 119.0 132.0 121.0 159.0 134.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.00 stddev:1.84 (1.1%) stddev-sans-first:1.87 15:13:04 INFO - PROCESS | 26900 | Values: 160.0 162.0 161.0 163.0 165.0 160.0 162.0 163.0 160.0 159.0 15:13:04 INFO - PROCESS | 26900 | 15:13:04 INFO - PROCESS | 26900 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 15:13:04 INFO - PROCESS | 26900 | Values: 60.0 61.0 60.0 60.0 60.0 59.0 59.0 59.0 58.0 59.0 15:13:04 INFO - PROCESS | 26900 | -------- Summary: end -------- 15:13:04 INFO - PROCESS | 26900 | 15:13:05 INFO - TEST-INFO | 26900: exit 0 15:13:05 INFO - TEST-OK | kraken | took 60115ms 15:13:05 INFO - SUITE-END | took 1175s 15:13:05 INFO - Completed test suite (00:19:35) 15:13:05 INFO - javascript benchmark 15:13:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7846.455889489295, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.1639590102473, 3376.712979396751, 3382.458806590946, 3416.2313479467107, 3433.193199598094, 5571.5542756579425, 5630.1554766785, 5630.211770352154, 5646.20953066571, 5666.9433305666935, 5646.74589643466, 5682.829515114543, 5688.776912963712, 5701.0, 5707.258056452655, 5090.949090509084, 5093.032323858473, 5097.694138351692, 5101.948980510203, 5169.250458683488, 4941.160002799533, 4955.653104282702, 5174.586033117361, 5180.974095129519, 5214.426413094557, 5070.163423035195, 5131.358580177478, 5133.0, 5143.639945203838, 5153.2270159476, 5125.692458452505, 5125.820596279131, 5129.743512824344, 5143.09995750744, 5152.613553983466, 4849.054434385296, 5050.873728156789, 5052.494750524955, 5083.288339632454, 5114.156164232897, 5475.67145971243, 5503.036968530531, 5547.944520554824, 5571.554275657938, 5577.8047768327915, 5968.552358573081, 5995.310539287994, 6043.516518678495, 6048.243969503812, 6049.092636104576, 6064.423879731418, 6069.787557435513, 6070.8785824283705, 6072.149899014142, 6079.787207447718, 5468.48049435303, 5504.834854954318, 5574.052411090124, 5580.441955804387, 5626.381098079208, 6155.72299246535, 6160.075988601746, 6164.260288765377, 6164.568480206342, 6164.969175154096, 5855.063189889597, 5925.288965324189, 5931.940680593225, 5932.762689492372, 5973.313068997034, 5750.453706897838, 5796.391378905154, 5899.646021238739, 5955.076963070731, 6004.969975150184, 5984.73068711909, 6000.099985002197, 6000.699965001732, 6023.789167379121, 6030.758769649253, 5772.307323121252, 5776.855578610484, 5782.739776710058, 5907.852303692442, 6023.728932198062, 4497.415336006298, 4518.638508919278, 4521.186186486399, 4526.59260666535, 4553.908921821545, 4012.658923991435, 4027.5368332641533, 4057.006033521747, 4106.67146628269, 4122.814473348706, 5847.356790753099, 5858.912116318259, 5860.2967643883, 5890.9410905891245, 5901.881962360729, 6051.485623722033, 6100.908486372708, 6102.603330783485, 6103.511719062464, 6122.602030868068, 5634.85912852182, 5664.20701101846, 5854.0, 5854.2389489366105, 5857.121431785266, 5043.142665746832, 5088.6946783193125, 5124.282600435942, 5139.331886854685, 5151.021305951932, 6216.6270023797815, 6248.812535623939, 6255.093011513305, 6257.186565746424, 6258.968705156536, 5829.912551311819, 6050.183225264709, 6051.909221361771, 6056.424639659225, 6063.242094738158, 6072.301685306246, 6153.907691384722, 6166.753329866755, 6250.687465626791, 6258.9374106260175, 5968.671723055274, 5968.820935371946, 5971.104334349882, 5973.940260597338, 5999.940000600112, 6042.395760423922, 6047.0627052806885, 6049.879002420015, 6069.878602427839, 6089.48239399656, 5679.801206957902, 5703.515201207943, 5822.70886455684, 5862.824115276549, 5950.077737950624, 5917.940820591739, 5918.3489816121055, 5921.0, 5924.555658325729, 5941.821745347646, 5670.121131224667, 5840.269966254218, 5900.173975643499, 5901.085331773582, 5908.970455147697, 5770.078615339644, 5820.447057529528, 5876.794312199053, 5889.823305300848, 6001.129836173643, 5848.766049357978, 5849.093390524476, 5853.64878107315, 5854.619449735754, 5880.852978675567], "value": 5546.823025120536, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.8692978728722, 806.4919100966391, 820.9425340226185, 891.7413949954514, 901.3420203251623, 31031.534526982112, 33459.33081338372, 43990.12019759607, 44755.0, 49053.50946490534, 43208.0, 43471.34792978108, 43594.0, 43625.78187109068, 43635.0, 7785.2214778521975, 9063.68277110303, 9203.0, 9207.263418926503, 9217.216536594398, 7731.149573546905, 10556.736081597983, 10782.514786834612, 10783.5686572537, 10786.460676966159, 10877.564897404134, 10978.231523793338, 10980.835287470694, 10987.505562249718, 10995.890041099567, 10788.08301294391, 10833.620823271227, 10923.0, 10926.180536459718, 10950.233483656148, 10571.682849514527, 10599.62901298451, 10613.257072004963, 10618.734531636694, 10629.574817007311, 42359.788201059106, 42482.78758606187, 42562.36156457655, 42573.57426425727, 42583.57416425827, 3957.248122856648, 4016.8393264269394, 4158.438610787535, 4187.455630768011, 4295.720778149442, 27323.0, 27376.577728071712, 27396.58905116425, 27821.582676259874, 28299.434011319863, 4368.126374725068, 4376.737395756265, 4387.254166791653, 4399.714018588781, 4491.101779644085, 269846.0, 269929.0, 271104.0, 271835.0, 271980.0, 49099.0, 49153.01693966101, 49288.75355623199, 49310.0, 49368.75315623399, 51903.48096519062, 51937.220941685904, 51991.0, 51995.74002130041, 52001.0, 15634.530964071097, 16080.839191608167, 16085.67828643444, 16091.114988675736, 16095.275712592962, 12004.51981920731, 12237.20458170216, 14024.439022438988, 14025.438982440588, 14045.0, 12970.935145324212, 13318.334083295991, 13532.25572593497, 13720.039597228299, 13835.654108647364, 5496.5053145216325, 5508.14623733322, 5581.190727344512, 5600.439956004367, 5624.831255062355, 1009.4801177393648, 1011.1102230037667, 1013.9898601013896, 1014.624588902111, 1028.1209565821116, 28724.281892952844, 28821.0, 28982.565261521093, 29356.853215733783, 29393.265168370534, 1083.3391631105178, 1084.1110289562482, 1094.7427354571669, 1103.3710784852717, 1115.5259014918627, 14511.201883896276, 15193.62015949588, 15430.614234644, 15511.457099001707, 15566.766498502531, 322037.0, 324672.0, 324844.0, 326241.0, 327053.0, 52942.735286324096, 53199.20201196985, 53319.73340133274, 53403.73298133562, 53422.73288633532, 1450.2821103553808, 1483.406637345049, 1485.7697056956406, 1513.7653663682147, 1519.5289460267352, 28212.85893570519, 29386.0, 29528.409431810815, 30048.0, 30215.546766798954, 40366.0, 40381.394279086424, 40410.39384409295, 40520.79739601283, 40641.1871762569, 349316.0, 351714.0, 351807.0, 352440.0, 352917.0, 26023.73976260213, 26534.203973880816, 27410.588841167, 31441.528377073904, 31985.88049418259, 39081.80459097686, 39699.40450893297, 39856.800715996236, 40203.39694904637, 40530.39204411877, 30835.537466938462, 30911.2272193197, 31073.0, 31261.687383126788, 31823.681763182998, 30693.23266918345, 30766.692333076382, 30774.230644234074, 30920.53619195669, 31564.210894727814, 34572.82713586416, 35208.0, 35346.293074138885, 35564.644353556134, 35685.0, 29560.113196604136, 29943.85028074846, 30257.848710756305, 30467.085987420414, 31167.844160779052, 26229.0, 26390.472190556466, 26448.735512644627, 26495.60256596191, 26966.191014268818], "value": 18738.79316770027, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.4123204762001, 468.58296116456347, 469.2679420104635, 479.5132940065832, 480.822095824545, 19322.420327390184, 20535.38393848183, 21459.0, 21862.0, 22045.11819527221, 22288.0, 22428.551428971412, 22837.4290642734, 22883.885580572114, 23172.188973385935, 6322.7470901164015, 6496.902546461807, 6607.834804129911, 6763.391294783469, 6815.216250131224, 6681.432078273328, 6803.931960680379, 6807.965960170192, 6966.233714291424, 7260.382867456273, 7380.114386273654, 7380.667869945865, 7394.334509894108, 7397.963010184942, 7398.7410440634585, 7316.122065352165, 7355.0070740450155, 7597.962010189969, 7614.581198034106, 7626.771196864048, 7400.407967362598, 7402.777916662509, 7451.888221676679, 7464.70141194357, 7474.103107627066, 25144.0, 25677.358066048313, 25745.871270643525, 25753.227403177934, 26025.869870650524, 3006.4438078955463, 3096.427160975227, 3131.749460043191, 3187.0916788715103, 3208.1819136120375, 15900.681986360209, 16460.753088703677, 16514.917425412918, 16680.49958501247, 16708.6658266834, 2204.2946257197705, 2221.6334304839684, 2264.784845439681, 2303.5968705476475, 2309.919152829643, 118829.0, 122274.38862805629, 122318.0, 122661.0, 122754.0, 18315.0, 18629.789063711087, 18807.341743038927, 18898.433047008613, 19113.426597202106, 32596.83701581477, 32674.0, 32719.67280327214, 32763.180920477178, 32980.67019329824, 10741.462926853626, 10797.0, 10861.0, 10861.511231994578, 11107.389093599768, 9113.498757568264, 9133.223675987483, 9137.177654011039, 9354.812903742024, 9376.48429336393, 9054.456732596065, 9287.953560232156, 9426.821647294088, 9483.146516813382, 9561.139497445125, 3682.502862113634, 3853.8013448837396, 3880.980595097063, 3910.4720862683744, 3964.4647972523917, 958.4680502321139, 958.6357184270072, 963.5375019990444, 969.1713584884963, 970.4953424219508, 19951.800481994993, 21102.577948440947, 21435.67846482304, 21625.0, 21747.0, 979.4221409368364, 983.7408117609472, 990.0891180114169, 991.791723738023, 997.8054279415444, 11197.66407007791, 11632.127590430751, 11796.46915888787, 11853.822192667118, 11864.22882512634, 649351.0, 663397.0, 671433.0, 671489.0, 680572.0, 95845.0, 109319.45340273248, 110378.0, 121474.0, 122114.0, 1327.9177470361624, 1344.88568471681, 1393.4147657983672, 1393.9651508712363, 1396.1064918451996, 19202.903985479985, 19347.322843700404, 19682.8031719681, 19703.507412314233, 19742.40772776819, 38117.61882381252, 38312.80843595764, 46843.531564683915, 53990.1103461377, 54321.0, 72864.0, 72929.63535182503, 73760.0, 74811.25188748042, 74882.0, 12250.081243906565, 12546.247225166519, 12763.744725105631, 12807.871921280668, 12820.358982050673, 13854.930725346308, 13860.168389896235, 14254.287285635884, 14446.927765361106, 14610.634734131732, 12311.19977201479, 12608.117431779687, 12696.238225706487, 12698.365081745691, 12840.743185136058, 9952.800943981225, 10065.993400660169, 10967.670969870916, 11622.360770157722, 11681.415929203336, 12613.36933153357, 12847.164934279243, 12945.352732363532, 12972.416241268977, 13064.608061758163, 12104.576339828063, 12272.509099635914, 12654.493820247088, 12792.74414511686, 13008.804867927178, 12484.313362765135, 12667.896365390538, 12801.679958001125, 12831.294278814756, 13039.674008149872], "value": 11697.883636229059, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.2334962469895, 1547.118142658685, 1563.155895816259, 1564.7027064857668, 1573.6695293988262, 9445.716628501148, 11523.596674116408, 11907.345096019715, 11914.582989595367, 11918.761624767521, 11583.710407239823, 11686.415679216027, 11691.298522088659, 11702.590409335675, 11708.297502149855, 1990.8805471671674, 2066.132224465728, 2402.6876506054186, 2433.0, 2433.8904749286235, 2396.9760302397012, 2463.3718401807514, 2471.6045432730766, 2476.8018558515278, 2479.4917042006346, 2372.2527403867725, 2399.3881560202126, 2404.723456802473, 2405.1581946318825, 2408.506256217471, 1999.2103119267954, 2073.6060148571723, 2329.592321343758, 2336.5794157051723, 2342.0514691550034, 2356.552255071531, 2441.816863735227, 2459.114718701284, 2468.8642124683133, 2471.7157526884457, 11577.421128943519, 11654.834516548362, 11675.649730508097, 11676.532938682443, 11683.299002059819, 5766.855828604276, 5911.29064512257, 5918.467337939563, 5918.763249470016, 5921.0, 3543.8228088595465, 3557.875474358385, 3640.4357324614725, 3702.796346200958, 3714.23858109087, 1339.0, 1344.744498545283, 1382.7234553089322, 1395.3073246196907, 1427.193635595885, 17405.129743512774, 17447.38934137299, 17472.65054698899, 17507.73738393925, 17541.73687394703, 4747.548982846624, 4998.350214472095, 5042.4957504250015, 5057.266696329012, 5122.410922743932, 13957.651058723613, 14006.43974241019, 14031.579052628438, 14049.227292498806, 14054.086484378482, 6015.6691381974415, 6049.818505444756, 6050.909236361458, 6053.697315134314, 6055.273367195965, 5313.973430132824, 5447.264619276347, 5461.180822876521, 5483.616146869761, 5493.2034854945805, 4303.784810759449, 4380.408644832907, 4399.956000440018, 4453.888652783707, 4454.643628509712, 1119.4346854838254, 1131.3607811586428, 1139.2480962564669, 1141.1099342512853, 1147.391882302381, 972.2610815779917, 993.7267251505893, 1007.5163921317809, 1011.8836333821557, 1012.5494155101056, 6495.8700825983215, 6575.967120164369, 6578.703958321887, 6663.833404164935, 6712.832179195461, 1018.4042335233971, 1029.1149611334247, 1041.037040737315, 1041.2971244410144, 1048.7692707604315, 4407.471103467605, 4415.337699345124, 4417.668674849399, 4433.091216300651, 4438.889027774267, 14016.579502614937, 14482.782758258634, 14602.780958285633, 14676.55970320892, 14678.41286348534, 14003.579892603238, 14303.356348964322, 14449.49426770058, 14473.348699308557, 14502.274886255856, 10229.693109206735, 10232.795344093076, 10266.640667576748, 10360.378377297386, 10374.844377334346, 6268.122462855113, 6297.559170857996, 6304.747810087545, 6357.14178585885, 6440.323766004502, 12213.145079844326, 12619.810702839648, 12648.114631975674, 12750.107492475438, 12762.29807360604, 12916.160449571124, 12988.025898057873, 12990.675233119247, 13014.674633134247, 13185.208887466782, 9705.078017588317, 9815.803683926424, 9866.703998880044, 9895.158911492605, 9915.702528924145, 10672.8932710675, 10769.892301076889, 10852.0, 10872.293300935415, 10878.292910960763, 9081.863772043293, 9224.677136300197, 9241.58412871409, 9346.532673366168, 9467.479288639192, 8816.471011739317, 8900.287976961958, 8950.104989500998, 9198.816023679356, 9245.075492450702, 7889.881651775343, 7958.84082318362, 8066.7579972600915, 8122.918770812453, 8170.346372290083, 5998.7300571473515, 6041.24484439445, 6086.087086936995, 6214.4407003369915, 6227.595206311757, 6327.240731112296, 6344.936550634435, 6348.206474190726, 6431.839204019937, 6495.512836537184], "value": 5370.900243016952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.80989649709386, 439.5538528393681, 439.6482813749001, 440.8302803420682, 442.8472177098902, 5280.7887684492625, 5346.144616861303, 5382.3541175059, 5384.6769193848295, 5401.91897121543, 5030.440563425341, 5275.36695596528, 5308.256844041827, 5309.73451327433, 5327.706976116312, 4350.825966961318, 4400.493943196533, 4400.713953593006, 4415.889602759925, 4429.756363400012, 4409.911801763963, 4437.4009508716235, 4448.132614140244, 4456.955430445686, 4469.44131983502, 4339.067100573376, 4382.671299652523, 4395.922998865282, 4427.402300689414, 4441.955580444186, 4346.195953748566, 4374.278244089722, 4389.319655453378, 4390.648748100144, 4403.405540252057, 4114.547399786021, 4128.938065929014, 4132.855350062733, 4136.400221967828, 4142.565030671766, 5202.0, 5213.374395072616, 5219.399769026534, 5240.973795131038, 5259.0796610593, 5040.798368065273, 5115.718635475047, 5116.2069879168785, 5156.484351564851, 5161.483851614846, 5510.531604813596, 5536.916946245809, 5566.415526369754, 5574.191742197399, 5575.52248654107, 4514.7291162530355, 4606.355110284563, 4622.653301002405, 4625.861224163281, 4673.813047478096, 5510.889782204334, 5550.335838797399, 5622.690752008638, 5802.390748971339, 5834.0, 4712.31671407644, 4733.0654993904, 4736.668433209711, 4743.359646447754, 4766.95233047672, 4830.420349558005, 4831.444383895827, 4835.081334546495, 4835.758212089381, 4838.0082083172865, 4792.496787837227, 4824.975875120602, 5117.41149767774, 5132.255822905732, 5168.664036837594, 5511.0, 5548.91676624851, 5552.888942221214, 5553.139263414178, 5571.554275657938, 1652.355581323285, 1662.9916850415912, 1675.5308513616208, 1678.3118921242233, 1681.4619321817145, 1235.6972541727155, 1301.1997621462733, 1310.1484035377082, 1315.9407826647823, 1331.8202042724301, 5335.506058303681, 5513.9448605513435, 5532.585056120807, 5553.861153471195, 5570.498655121059, 5702.714864256854, 5747.655140691572, 5754.568407369464, 5785.971070144622, 5787.565932554991, 5517.779288828482, 5535.308086489189, 5543.833684989457, 5571.777128914798, 5594.300712410949, 2890.869910854017, 3028.424599326121, 3052.168698780714, 3055.816651000947, 3057.4649436348996, 5891.909996650634, 5904.261967254093, 5936.614120082181, 5998.0, 6055.485283750931, 5670.5463562914865, 5714.000099964979, 5717.285339332583, 5926.377730338252, 5939.346671866091, 5837.211976383134, 5904.608633503524, 5914.556408269397, 5922.318933322636, 5932.911006334822, 5676.829695109153, 5816.680082595329, 5823.359430462561, 5832.366937257405, 5833.329167145663, 5358.356997160366, 5663.405342438985, 5682.204491371129, 5689.77240910359, 5702.486776190163, 5654.558087687613, 5716.942830571641, 5903.616264942765, 5961.612495187799, 6015.608985415934, 4753.429588449409, 4765.237561990065, 4831.130396528518, 4844.588210002189, 4851.757412129309, 5444.047291724011, 5707.942920570741, 5791.478766910998, 5852.507610559451, 5877.500412464818, 5480.753366098455, 5497.202905578589, 5512.972435137799, 5571.749271282721, 5608.242887210175, 5563.217012431585, 5563.58273129525, 5570.599226611094, 5581.525570326406, 5594.86012849682, 5307.6284660073425, 5540.473655002768, 5570.832875013756, 5574.331080270394, 5581.944180558305, 5610.214569960127, 5620.775168993358, 5647.350554686263, 5652.293463317085, 5665.603407761417, 5459.727013649381, 5524.508382736558, 5549.389567147691, 5590.8043218487155, 5615.775368985358], "value": 4492.870445845642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.4290739389825, 891.656712165816, 899.5142622983585, 909.5043201455206, 929.6327950459569, 16808.747868781964, 17952.64094718108, 18089.638207235846, 18106.818931810678, 18198.72701909471, 16072.276747546359, 16368.427105051296, 16417.507474775746, 16424.507264782045, 16479.67040659186, 871.895372555291, 896.7668406214366, 902.1835239108617, 908.2416182487631, 923.4690053219425, 903.4263242840777, 903.4850135422812, 907.3240435875282, 909.122696597783, 909.2589539525266, 961.2742379503466, 963.9277054220927, 964.5900492290783, 964.9903500964971, 966.0870477398826, 962.5476026267643, 962.7496850818768, 963.2390411574883, 963.330485312708, 963.9132478076972, 700.1702981966336, 702.3468174597622, 703.0684343245221, 703.5778532880282, 703.725547036651, 18065.187066582035, 18066.36767713124, 18102.0, 18515.074246287633, 18737.0, 15917.602059948478, 15989.680206395924, 16196.514104576881, 16405.753913691304, 16793.07638079905, 12956.157849739737, 13166.934165329112, 13306.573502812184, 13633.386497607631, 13716.931415342859, 4112.712110152291, 4125.133721918401, 4132.938005929914, 4212.326027835532, 4214.473190851144, 88481.55759221227, 89875.55062224713, 89938.99999999935, 91370.0, 91553.54223228841, 12446.315452650191, 12504.81242781359, 12546.749065018648, 12564.120511564102, 12742.0, 23605.1738189166, 24356.634650480257, 24636.0, 24684.38289042788, 24777.62833557498, 9296.488693121808, 9469.810603787886, 9769.706908792748, 10038.899611003942, 10103.84844227337, 5044.066847633199, 5258.474152584711, 5315.548178404879, 5336.17289320156, 5411.485908838654, 3797.5063241778394, 3924.843006279717, 3929.332013557702, 4160.812763425653, 4323.718958267703, 722.794003708951, 723.8588475247276, 725.7169703815516, 733.5012191709689, 734.4931996922106, 595.7795615622248, 607.3713706313944, 608.0878681977034, 647.4334956912701, 827.462149602751, 11131.773645270978, 11244.66266012021, 11267.535419803171, 12011.099167562468, 12200.938995305145, 668.1014036121409, 669.4677731203705, 684.9863002739917, 718.9964050179716, 766.172533663642, 5505.476979686923, 5696.321600079228, 5726.885462290731, 5738.0, 5788.334341550775, 83692.58153709314, 84720.15279847123, 85953.5702321497, 87877.56061219652, 89258.55370723105, 43479.782601086794, 50457.0, 67935.32064679389, 71431.28568714246, 71450.64274678593, 51418.228726569134, 61184.0, 61734.69132654308, 61882.0, 63400.999999999076, 11135.276207046516, 11508.712282193012, 11578.76842463163, 11585.88414115848, 11862.584809531625, 41395.0, 42048.57951420569, 42240.0, 42762.0, 43633.56366436422, 47204.29193562168, 47703.28445073257, 48022.0, 48284.75857620689, 49553.752231240054, 13599.59201223965, 13628.0, 13633.522826701017, 13847.376868040761, 14434.566962991126, 16668.833311666727, 16716.66566668684, 16951.915240423717, 17051.147442628066, 17131.314747409964, 15034.398624054915, 15088.471903483325, 15371.923140384226, 15691.607709807346, 15838.208089595704, 14303.21332326732, 14629.487967921072, 14690.55928322152, 15002.924985375439, 15631.452899148475, 14979.176145312113, 15011.42259642312, 15253.313600888208, 15469.922650386676, 15828.52514424569, 11712.648620541397, 11874.82187767167, 12341.0, 12488.664334758374, 12589.685257868627, 11266.718332041764, 11923.642290731292, 12349.75300494003, 12441.60829573512, 12650.493980240688], "value": 7953.135756253642, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1461.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 114.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 122.0, 150.0, 122.0, 121.0, 116.0, 116.0, 136.0, 115.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 171.0, 162.0, 162.0, 161.0, 162.0, 161.0, 162.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 115.0, 75.0, 77.0, 76.0, 76.0, 91.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 111.0, 83.0, 82.0, 82.0, 101.0, 84.0, 82.0, 110.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 148.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 95.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 53.0, 54.0, 54.0, 55.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 54.0, 53.0, 53.0, 54.0, 54.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 76.0, 78.0, 81.0, 75.0, 82.0, 74.0, 78.0, 79.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 140.0, 122.0, 121.0, 126.0, 119.0, 132.0, 121.0, 159.0, 134.0], "value": 129.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 163.0, 165.0, 160.0, 162.0, 163.0, 160.0, 159.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 60.0, 59.0, 59.0, 59.0, 58.0, 59.0], "value": 59.5, "unit": "score"}]}]} 15:13:06 INFO - Return code: 0 15:13:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:13:06 ERROR - # TBPL SUCCESS # 15:13:06 INFO - Running post-action listener: _resource_record_post_action 15:13:06 INFO - [mozharness: 2017-04-07 22:13:06.087020Z] Finished run-tests step (success) 15:13:06 INFO - Running post-run listener: _resource_record_post_run 15:13:06 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 365654016; Read time: 28; Write time: 233112 15:13:06 INFO - TinderboxPrint: CPU usage
13.1% 15:13:06 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 15:13:06 INFO - TinderboxPrint: I/O write bytes / time
365,654,016 / 233,112 15:13:06 INFO - TinderboxPrint: CPU idle
8,264.5 (86.9%) 15:13:06 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 15:13:06 INFO - TinderboxPrint: Swap in / out
0 / 0 15:13:06 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110772224; Read time: 0; Write time: 96668 15:13:06 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254881792; Read time: 28; Write time: 136444 15:13:06 INFO - Running post-run listener: _upload_blobber_files 15:13:06 INFO - Blob upload gear active. 15:13:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:13:06 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 15:13:06 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'] 15:13:06 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 15:13:07 INFO - (blobuploader) - INFO - Open directory for files ... 15:13:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:13:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:13:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:08 INFO - (blobuploader) - INFO - Done attempting. 15:13:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:13:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:13:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:10 INFO - (blobuploader) - INFO - Done attempting. 15:13:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:13:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:13:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:11 INFO - (blobuploader) - INFO - Done attempting. 15:13:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:13:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:13:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:12 INFO - (blobuploader) - INFO - Done attempting. 15:13:12 INFO - (blobuploader) - INFO - Iteration through files over. 15:13:12 INFO - Return code: 0 15:13:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:13:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:13:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bf1b41f32dff4d01c03fcc4d6690a14aef9b67e09fa794c3dcb92f09ba27b77ead9e8e9aa9c329cc9a25b8591c1ef1ab1a7fee04095e5de5e35efebb29907a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/683ff60f11075a41a248ecdf84a7c5409fe8557d43ca54c57a8a0b4a76f6e3bea30a84a8ffae11ce995b41136c71514aa3d275ef2caf578667c5d4a857eb79dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0fb0dad7d78f16455ec5938da6e190d696245e8231e58c7008ee6f07f855aa1c0f474d5ddf533fd4bf3a4949531bab3cd77bebc527d0dffb64f9f4f7fc4fcfca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baefd42c4aa78696835a9881af5dd591377bb1e757aecba7652d965d47b91ba11db8a675acb14403b9ca575edba27c2491874698b5b4378acbb700795f6cee85"} 15:13:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:13:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:13:12 INFO - Contents: 15:13:12 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bf1b41f32dff4d01c03fcc4d6690a14aef9b67e09fa794c3dcb92f09ba27b77ead9e8e9aa9c329cc9a25b8591c1ef1ab1a7fee04095e5de5e35efebb29907a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/683ff60f11075a41a248ecdf84a7c5409fe8557d43ca54c57a8a0b4a76f6e3bea30a84a8ffae11ce995b41136c71514aa3d275ef2caf578667c5d4a857eb79dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0fb0dad7d78f16455ec5938da6e190d696245e8231e58c7008ee6f07f855aa1c0f474d5ddf533fd4bf3a4949531bab3cd77bebc527d0dffb64f9f4f7fc4fcfca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baefd42c4aa78696835a9881af5dd591377bb1e757aecba7652d965d47b91ba11db8a675acb14403b9ca575edba27c2491874698b5b4378acbb700795f6cee85"} 15:13:12 INFO - Running post-run listener: copy_logs_to_upload_dir 15:13:12 INFO - Copying logs to upload dir... 15:13:12 INFO - mkdir: /builds/slave/test/build/upload/logs 15:13:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.104623 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-07 15:13:12.485655) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 15:13:12.489122) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bf1b41f32dff4d01c03fcc4d6690a14aef9b67e09fa794c3dcb92f09ba27b77ead9e8e9aa9c329cc9a25b8591c1ef1ab1a7fee04095e5de5e35efebb29907a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/683ff60f11075a41a248ecdf84a7c5409fe8557d43ca54c57a8a0b4a76f6e3bea30a84a8ffae11ce995b41136c71514aa3d275ef2caf578667c5d4a857eb79dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0fb0dad7d78f16455ec5938da6e190d696245e8231e58c7008ee6f07f855aa1c0f474d5ddf533fd4bf3a4949531bab3cd77bebc527d0dffb64f9f4f7fc4fcfca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baefd42c4aa78696835a9881af5dd591377bb1e757aecba7652d965d47b91ba11db8a675acb14403b9ca575edba27c2491874698b5b4378acbb700795f6cee85"} build_url:https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/GX2SqMHHQIqt6fImp4dIdw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bf1b41f32dff4d01c03fcc4d6690a14aef9b67e09fa794c3dcb92f09ba27b77ead9e8e9aa9c329cc9a25b8591c1ef1ab1a7fee04095e5de5e35efebb29907a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/683ff60f11075a41a248ecdf84a7c5409fe8557d43ca54c57a8a0b4a76f6e3bea30a84a8ffae11ce995b41136c71514aa3d275ef2caf578667c5d4a857eb79dc", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0fb0dad7d78f16455ec5938da6e190d696245e8231e58c7008ee6f07f855aa1c0f474d5ddf533fd4bf3a4949531bab3cd77bebc527d0dffb64f9f4f7fc4fcfca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baefd42c4aa78696835a9881af5dd591377bb1e757aecba7652d965d47b91ba11db8a675acb14403b9ca575edba27c2491874698b5b4378acbb700795f6cee85"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 15:13:12.514018) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:13:12.514350) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1491601939.795725-35679528 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:13:12.598742) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 15:13:12.599088) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 15:13:12.599431) ========= ========= Total master_lag: 0.17 =========