builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-066 starttime: 1486436768.76 results: success (0) revision: fcc32735c01d504767a0dee94c6bc706b6a23b5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.763857) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.764354) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.764630) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.781742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.782165) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-06 19:06:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 416K=0.03s 2017-02-06 19:06:09 (416 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116978 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.912615) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.912946) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013875 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:08.965955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 19:06:08.966325) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcc32735c01d504767a0dee94c6bc706b6a23b5e --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 fcc32735c01d504767a0dee94c6bc706b6a23b5e --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=82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-06 19:06:09,706 truncating revision to first 12 chars 2017-02-06 19:06:09,706 Setting DEBUG logging. 2017-02-06 19:06:09,706 attempt 1/10 2017-02-06 19:06:09,706 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcc32735c01d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 19:06:10,709 unpacking tar archive at: mozilla-aurora-fcc32735c01d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.356176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 19:06:10.338231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:10.338606) ========= script_repo_revision: fcc32735c01d504767a0dee94c6bc706b6a23b5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:10.339036) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:10.339320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 19:06:10.349693) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-02-06 19:06:10.350096) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:06:11 INFO - MultiFileLogger online at 20170206 19:06:11 in /builds/slave/test 19:06:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 19:06:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:06:11 INFO - {'append_to_log': False, 19:06:11 INFO - 'base_work_dir': '/builds/slave/test', 19:06:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 19:06:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:06:11 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 19:06:11 INFO - 'buildbot_json_path': 'buildprops.json', 19:06:11 INFO - 'config_files': ('talos/linux_config.py',), 19:06:11 INFO - 'default_actions': ('clobber', 19:06:11 INFO - 'read-buildbot-config', 19:06:11 INFO - 'download-and-extract', 19:06:11 INFO - 'populate-webroot', 19:06:11 INFO - 'create-virtualenv', 19:06:11 INFO - 'install', 19:06:11 INFO - 'run-tests'), 19:06:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:06:11 INFO - 'download_minidump_stackwalk': True, 19:06:11 INFO - 'download_symbols': 'ondemand', 19:06:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:06:11 INFO - 'tooltool.py': '/tools/tooltool.py', 19:06:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:06:11 INFO - '/tools/misc-python/virtualenv.py')}, 19:06:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:06:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:06:11 INFO - 'installer_path': 'installer.exe', 19:06:11 INFO - 'log_level': 'info', 19:06:11 INFO - 'log_name': 'talos', 19:06:11 INFO - 'log_to_console': True, 19:06:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:06:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:06:11 INFO - 'opt_config_files': (), 19:06:11 INFO - 'pip_index': False, 19:06:11 INFO - 'sps_profile': False, 19:06:11 INFO - 'sps_profile_interval': 0, 19:06:11 INFO - 'suite': 'dromaeojs', 19:06:11 INFO - 'system_bits': '32', 19:06:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:06:11 INFO - 'title': 'talos-linux64-ix-066', 19:06:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:06:11 INFO - 'use_talos_json': True, 19:06:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:06:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:06:11 INFO - 'work_dir': 'build'} 19:06:11 INFO - [mozharness: 2017-02-07 03:06:11.184089Z] Running clobber step. 19:06:11 INFO - Running pre-action listener: _resource_record_pre_action 19:06:11 INFO - Running main action method: clobber 19:06:11 INFO - rmtree: /builds/slave/test/build 19:06:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:06:11 INFO - Running post-action listener: _resource_record_post_action 19:06:11 INFO - [mozharness: 2017-02-07 03:06:11.659508Z] Finished clobber step (success) 19:06:11 INFO - [mozharness: 2017-02-07 03:06:11.659612Z] Running read-buildbot-config step. 19:06:11 INFO - Running pre-action listener: _resource_record_pre_action 19:06:11 INFO - Running main action method: read_buildbot_config 19:06:11 INFO - Using buildbot properties: 19:06:11 INFO - { 19:06:11 INFO - "project": "", 19:06:11 INFO - "product": "firefox", 19:06:11 INFO - "who": "ryanvm@gmail.com", 19:06:11 INFO - "installer_path": "public/build/target.tar.bz2", 19:06:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:06:11 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 19:06:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 19:06:11 INFO - "stage_platform": "linux64", 19:06:11 INFO - "basedir": "/builds/slave/test", 19:06:11 INFO - "buildnumber": 21, 19:06:11 INFO - "platform": "ubuntu64_hw", 19:06:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:06:11 INFO - "slavebuilddir": "test", 19:06:11 INFO - "taskId": "H8rFup0NQxKDIOtQoTFjiQ", 19:06:11 INFO - "branch": "mozilla-aurora", 19:06:11 INFO - "script_repo_revision": "production", 19:06:11 INFO - "revision": "fcc32735c01d504767a0dee94c6bc706b6a23b5e", 19:06:11 INFO - "slavename": "talos-linux64-ix-066", 19:06:11 INFO - "repo_path": "releases/mozilla-aurora" 19:06:11 INFO - } 19:06:11 INFO - Finding installer, test and symbols from parent task. 19:06:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H8rFup0NQxKDIOtQoTFjiQ'}, attempt #1 19:06:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H8rFup0NQxKDIOtQoTFjiQ'}, attempt #1 19:06:12 INFO - Task dependencies: NW42p2AWTYaC43yHGdSLQg 19:06:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg'}, attempt #1 19:06:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2 19:06:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/test_packages.json 19:06:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.crashreporter-symbols.zip 19:06:13 INFO - Running post-action listener: _resource_record_post_action 19:06:13 INFO - [mozharness: 2017-02-07 03:06:13.071072Z] Finished read-buildbot-config step (success) 19:06:13 INFO - [mozharness: 2017-02-07 03:06:13.071292Z] Running download-and-extract step. 19:06:13 INFO - Running pre-action listener: _resource_record_pre_action 19:06:13 INFO - Running main action method: download_and_extract 19:06:13 INFO - mkdir: /builds/slave/test/build/tests 19:06: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')]} 19:06:13 INFO - trying https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.test_packages.json 19:06:13 INFO - Downloading https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:06:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:06:13 INFO - Downloaded 896 bytes. 19:06:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:06:13 INFO - Using the following test package requirements: 19:06:13 INFO - {u'common': [u'target.common.tests.zip'], 19:06:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:06:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:06:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:06:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:06:13 INFO - u'mozbase': [u'target.common.tests.zip'], 19:06:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:06:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:06:13 INFO - u'web-platform': [u'target.common.tests.zip', 19:06:13 INFO - u'target.web-platform.tests.zip'], 19:06:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:06:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:06:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.common.tests.zip 19:06:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:06:13 INFO - Fetch https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.common.tests.zip into memory 19:06:17 INFO - Expected file size: 21857958 19:06:17 INFO - Obtained file size: 21857958 19:06:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:06:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.common.tests.zip 19:06:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:06:21 INFO - Fetch https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.common.tests.zip into memory 19:06:23 INFO - Expected file size: 21857958 19:06:23 INFO - Obtained file size: 21857958 19:06:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.talos.tests.zip 19:06:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:06:27 INFO - Fetch https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.talos.tests.zip into memory 19:06:29 INFO - Expected file size: 14403485 19:06:29 INFO - Obtained file size: 14403485 19:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:29 INFO - trying https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2 19:06:29 INFO - Downloading https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:06:31 INFO - Downloaded 66113804 bytes. 19:06:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2 19:06:31 INFO - mkdir: /builds/slave/test/properties 19:06:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:06:31 INFO - Writing to file /builds/slave/test/properties/build_url 19:06:31 INFO - Contents: 19:06:31 INFO - build_url:https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2 19:06:31 INFO - Running post-action listener: _resource_record_post_action 19:06:31 INFO - Running post-action listener: set_extra_try_arguments 19:06:31 INFO - [mozharness: 2017-02-07 03:06:31.891584Z] Finished download-and-extract step (success) 19:06:31 INFO - [mozharness: 2017-02-07 03:06:31.891682Z] Running populate-webroot step. 19:06:31 INFO - Running pre-action listener: _resource_record_pre_action 19:06:31 INFO - Running main action method: populate_webroot 19:06:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:06:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:06:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:06:31 INFO - '--tppagecycles', 19:06:31 INFO - '7'], 19:06:31 INFO - 'tests': ['tsvgm']}}, 19:06:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:06:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:06:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:06:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:06:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:06:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:06:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['damp', 'tps']}, 19:06:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'tests': ['damp', 'tps']}, 19:06:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['dromaeo_dom']}, 19:06:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:06:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['basic_compositor_video']}, 19:06:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:06:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['a11yr', 19:06:31 INFO - 'ts_paint', 19:06:31 INFO - 'tpaint', 19:06:31 INFO - 'sessionrestore', 19:06:31 INFO - 'sessionrestore_no_auto_restore', 19:06:31 INFO - 'tabpaint']}, 19:06:31 INFO - 'other-e10s': {'tests': ['a11yr', 19:06:31 INFO - 'ts_paint', 19:06:31 INFO - 'tpaint', 19:06:31 INFO - 'sessionrestore', 19:06:31 INFO - 'sessionrestore_no_auto_restore', 19:06:31 INFO - 'tabpaint']}, 19:06:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:06:31 INFO - 'ts_paint', 19:06:31 INFO - 'tpaint', 19:06:31 INFO - 'sessionrestore', 19:06:31 INFO - 'sessionrestore_no_auto_restore', 19:06:31 INFO - 'tabpaint']}, 19:06:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:06:31 INFO - 'ts_paint', 19:06:31 INFO - 'tpaint', 19:06:31 INFO - 'sessionrestore', 19:06:31 INFO - 'sessionrestore_no_auto_restore', 19:06:31 INFO - 'tabpaint']}, 19:06:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['a11yr', 19:06:31 INFO - 'ts_paint', 19:06:31 INFO - 'tpaint', 19:06:31 INFO - 'sessionrestore', 19:06:31 INFO - 'sessionrestore_no_auto_restore', 19:06:31 INFO - 'tabpaint']}, 19:06:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['a11yr', 19:06:31 INFO - 'ts_paint', 19:06:31 INFO - 'tpaint', 19:06:31 INFO - 'sessionrestore', 19:06:31 INFO - 'sessionrestore_no_auto_restore', 19:06:31 INFO - 'tabpaint']}, 19:06:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['tsvgx', 19:06:31 INFO - 'tsvgr_opacity', 19:06:31 INFO - 'tart', 19:06:31 INFO - 'tscrollx', 19:06:31 INFO - 'cart', 19:06:31 INFO - 'tsvg_static']}, 19:06:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:06:31 INFO - 'tsvgr_opacity', 19:06:31 INFO - 'tart', 19:06:31 INFO - 'tscrollx', 19:06:31 INFO - 'cart', 19:06:31 INFO - 'tsvg_static']}, 19:06:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'talos_options': ['--disable-e10s'], 19:06:31 INFO - 'tests': ['tp5o']}, 19:06:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:06:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'talos_options': ['--disable-e10s', 19:06:31 INFO - '--xperf_path', 19:06:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:06:31 INFO - 'tests': ['tp5n']}, 19:06:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:06:31 INFO - 'talos_options': ['--xperf_path', 19:06:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:06:31 INFO - 'tests': ['tp5n']}}, 19:06:31 INFO - 'talos.zip': {'path': '', 19:06:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:06:31 INFO - Running post-action listener: _resource_record_post_action 19:06:31 INFO - [mozharness: 2017-02-07 03:06:31.904936Z] Finished populate-webroot step (success) 19:06:31 INFO - [mozharness: 2017-02-07 03:06:31.905027Z] Running create-virtualenv step. 19:06:31 INFO - Running pre-action listener: _resource_record_pre_action 19:06:31 INFO - Running main action method: create_virtualenv 19:06:31 INFO - Creating virtualenv /builds/slave/test/build/venv 19:06:31 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 19:06:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:06:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:06:31 INFO - Using real prefix '/usr' 19:06:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:06:32 INFO - Installing distribute.............................................................................................................................................................................................done. 19:06:34 INFO - Installing pip.................done. 19:06:34 INFO - Return code: 0 19:06:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:06:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:06:34 INFO - Reading from file tmpfile_stdout 19:06:34 INFO - Output received: 19:06:34 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) 19:06:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:06:34 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')]} 19:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:34 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')]} 19:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:34 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:34 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 19:06:34 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 19:06:34 INFO - Using env: {'DISPLAY': ':0', 19:06:34 INFO - 'HOME': '/home/cltbld', 19:06:34 INFO - 'LANG': 'en_US.UTF-8', 19:06:34 INFO - 'LANGUAGE': 'en_US:en', 19:06:34 INFO - 'LOGNAME': 'cltbld', 19:06:34 INFO - 'MAIL': '/var/mail/cltbld', 19:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:34 INFO - 'MOZ_NO_REMOTE': '1', 19:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:34 INFO - 'NO_EM_RESTART': '1', 19:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:34 INFO - 'PWD': '/builds/slave/test', 19:06:34 INFO - 'SHELL': '/bin/bash', 19:06:34 INFO - 'SHLVL': '1', 19:06:34 INFO - 'TERM': 'linux', 19:06:34 INFO - 'TMOUT': '86400', 19:06:34 INFO - 'USER': 'cltbld', 19:06:34 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:34 INFO - '_': '/tools/buildbot/bin/python'} 19:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:34 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 19:06:34 INFO - Cleaning up... 19:06:34 INFO - Return code: 0 19:06:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:06:34 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')]} 19:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:34 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')]} 19:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:34 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:34 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 19:06:34 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 19:06:34 INFO - Using env: {'DISPLAY': ':0', 19:06:34 INFO - 'HOME': '/home/cltbld', 19:06:34 INFO - 'LANG': 'en_US.UTF-8', 19:06:34 INFO - 'LANGUAGE': 'en_US:en', 19:06:34 INFO - 'LOGNAME': 'cltbld', 19:06:34 INFO - 'MAIL': '/var/mail/cltbld', 19:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:34 INFO - 'MOZ_NO_REMOTE': '1', 19:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:34 INFO - 'NO_EM_RESTART': '1', 19:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:34 INFO - 'PWD': '/builds/slave/test', 19:06:34 INFO - 'SHELL': '/bin/bash', 19:06:34 INFO - 'SHLVL': '1', 19:06:34 INFO - 'TERM': 'linux', 19:06:34 INFO - 'TMOUT': '86400', 19:06:34 INFO - 'USER': 'cltbld', 19:06:34 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:34 INFO - '_': '/tools/buildbot/bin/python'} 19:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:35 INFO - Downloading/unpacking psutil>=3.1.1 19:06:35 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 19:06:35 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 19:06:35 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 19:06:35 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 19:06:35 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 19:06:35 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 19:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:06:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:06:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:06:37 INFO - Installing collected packages: psutil 19:06:37 INFO - Running setup.py install for psutil 19:06:37 INFO - building 'psutil._psutil_linux' extension 19:06:37 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 19:06:37 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 19:06:37 INFO - building 'psutil._psutil_posix' extension 19:06:37 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 19:06:37 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 19:06:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:06:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:06:37 INFO - Successfully installed psutil 19:06:37 INFO - Cleaning up... 19:06:37 INFO - Return code: 0 19:06:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:06:37 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')]} 19:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:37 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')]} 19:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:37 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:37 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 19:06:37 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 19:06:37 INFO - Using env: {'DISPLAY': ':0', 19:06:37 INFO - 'HOME': '/home/cltbld', 19:06:37 INFO - 'LANG': 'en_US.UTF-8', 19:06:37 INFO - 'LANGUAGE': 'en_US:en', 19:06:37 INFO - 'LOGNAME': 'cltbld', 19:06:37 INFO - 'MAIL': '/var/mail/cltbld', 19:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:37 INFO - 'MOZ_NO_REMOTE': '1', 19:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:37 INFO - 'NO_EM_RESTART': '1', 19:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:37 INFO - 'PWD': '/builds/slave/test', 19:06:37 INFO - 'SHELL': '/bin/bash', 19:06:37 INFO - 'SHLVL': '1', 19:06:37 INFO - 'TERM': 'linux', 19:06:37 INFO - 'TMOUT': '86400', 19:06:37 INFO - 'USER': 'cltbld', 19:06:37 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:37 INFO - '_': '/tools/buildbot/bin/python'} 19:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:06:38 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 19:06:38 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 19:06:38 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 19:06:38 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 19:06:38 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 19:06:38 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 19:06:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:06:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:06:40 INFO - Installing collected packages: mozsystemmonitor 19:06:40 INFO - Running setup.py install for mozsystemmonitor 19:06:40 INFO - Successfully installed mozsystemmonitor 19:06:40 INFO - Cleaning up... 19:06:40 INFO - Return code: 0 19:06:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:06:40 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')]} 19:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:40 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')]} 19:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:40 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:40 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 19:06:40 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 19:06:40 INFO - Using env: {'DISPLAY': ':0', 19:06:40 INFO - 'HOME': '/home/cltbld', 19:06:40 INFO - 'LANG': 'en_US.UTF-8', 19:06:40 INFO - 'LANGUAGE': 'en_US:en', 19:06:40 INFO - 'LOGNAME': 'cltbld', 19:06:40 INFO - 'MAIL': '/var/mail/cltbld', 19:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:40 INFO - 'MOZ_NO_REMOTE': '1', 19:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:40 INFO - 'NO_EM_RESTART': '1', 19:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:40 INFO - 'PWD': '/builds/slave/test', 19:06:40 INFO - 'SHELL': '/bin/bash', 19:06:40 INFO - 'SHLVL': '1', 19:06:40 INFO - 'TERM': 'linux', 19:06:40 INFO - 'TMOUT': '86400', 19:06:40 INFO - 'USER': 'cltbld', 19:06:40 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:40 INFO - '_': '/tools/buildbot/bin/python'} 19:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:06:40 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 19:06:40 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 19:06:40 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 19:06:40 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 19:06:40 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 19:06:40 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 19:06:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:06:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:06:42 INFO - Downloading functools32-3.2.3-2.tar.gz 19:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:06:42 INFO - warning: no files found matching '*.txt' 19:06:42 INFO - no previously-included directories found matching 'build' 19:06:42 INFO - no previously-included directories found matching 'dist' 19:06:42 INFO - no previously-included directories found matching '.git*' 19:06:42 INFO - Installing collected packages: jsonschema, functools32 19:06:42 INFO - Running setup.py install for functools32 19:06:43 INFO - warning: no files found matching '*.txt' 19:06:43 INFO - no previously-included directories found matching 'build' 19:06:43 INFO - no previously-included directories found matching 'dist' 19:06:43 INFO - no previously-included directories found matching '.git*' 19:06:43 INFO - Successfully installed jsonschema functools32 19:06:43 INFO - Cleaning up... 19:06:43 INFO - Return code: 0 19:06:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:43 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:43 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 19:06:43 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 19:06:43 INFO - Using env: {'DISPLAY': ':0', 19:06:43 INFO - 'HOME': '/home/cltbld', 19:06:43 INFO - 'LANG': 'en_US.UTF-8', 19:06:43 INFO - 'LANGUAGE': 'en_US:en', 19:06:43 INFO - 'LOGNAME': 'cltbld', 19:06:43 INFO - 'MAIL': '/var/mail/cltbld', 19:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:43 INFO - 'MOZ_NO_REMOTE': '1', 19:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:43 INFO - 'NO_EM_RESTART': '1', 19:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:43 INFO - 'PWD': '/builds/slave/test', 19:06:43 INFO - 'SHELL': '/bin/bash', 19:06:43 INFO - 'SHLVL': '1', 19:06:43 INFO - 'TERM': 'linux', 19:06:43 INFO - 'TMOUT': '86400', 19:06:43 INFO - 'USER': 'cltbld', 19:06:43 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:43 INFO - '_': '/tools/buildbot/bin/python'} 19:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:06:43 INFO - Cleaning up... 19:06:43 INFO - Return code: 0 19:06:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:43 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:43 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 19:06:43 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 19:06:43 INFO - Using env: {'DISPLAY': ':0', 19:06:43 INFO - 'HOME': '/home/cltbld', 19:06:43 INFO - 'LANG': 'en_US.UTF-8', 19:06:43 INFO - 'LANGUAGE': 'en_US:en', 19:06:43 INFO - 'LOGNAME': 'cltbld', 19:06:43 INFO - 'MAIL': '/var/mail/cltbld', 19:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:43 INFO - 'MOZ_NO_REMOTE': '1', 19:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:43 INFO - 'NO_EM_RESTART': '1', 19:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:43 INFO - 'PWD': '/builds/slave/test', 19:06:43 INFO - 'SHELL': '/bin/bash', 19:06:43 INFO - 'SHLVL': '1', 19:06:43 INFO - 'TERM': 'linux', 19:06:43 INFO - 'TMOUT': '86400', 19:06:43 INFO - 'USER': 'cltbld', 19:06:43 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:43 INFO - '_': '/tools/buildbot/bin/python'} 19:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:43 INFO - Downloading/unpacking blobuploader==1.2.4 19:06:43 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 19:06:43 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 19:06:43 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 19:06:43 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 19:06:43 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 19:06:43 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 19:06:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:06:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:06:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:06:46 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 19:06:46 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 19:06:46 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 19:06:46 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 19:06:46 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 19:06:46 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 19:06:46 INFO - Downloading docopt-0.6.1.tar.gz 19:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:06:46 INFO - Running setup.py install for blobuploader 19:06:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:06:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:06:46 INFO - Running setup.py install for requests 19:06:46 INFO - Running setup.py install for docopt 19:06:47 INFO - Successfully installed blobuploader requests docopt 19:06:47 INFO - Cleaning up... 19:06:47 INFO - Return code: 0 19:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:47 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:47 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 19:06:47 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 19:06:47 INFO - Using env: {'DISPLAY': ':0', 19:06:47 INFO - 'HOME': '/home/cltbld', 19:06:47 INFO - 'LANG': 'en_US.UTF-8', 19:06:47 INFO - 'LANGUAGE': 'en_US:en', 19:06:47 INFO - 'LOGNAME': 'cltbld', 19:06:47 INFO - 'MAIL': '/var/mail/cltbld', 19:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:47 INFO - 'MOZ_NO_REMOTE': '1', 19:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:47 INFO - 'NO_EM_RESTART': '1', 19:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:47 INFO - 'PWD': '/builds/slave/test', 19:06:47 INFO - 'SHELL': '/bin/bash', 19:06:47 INFO - 'SHLVL': '1', 19:06:47 INFO - 'TERM': 'linux', 19:06:47 INFO - 'TMOUT': '86400', 19:06:47 INFO - 'USER': 'cltbld', 19:06:47 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:47 INFO - '_': '/tools/buildbot/bin/python'} 19:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:06:47 INFO - Running setup.py (path:/tmp/pip-smPEeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:06:47 INFO - Running setup.py (path:/tmp/pip-X95PZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:06:47 INFO - Running setup.py (path:/tmp/pip-c90xx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:06:47 INFO - Running setup.py (path:/tmp/pip-wZi12b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:06:47 INFO - Running setup.py (path:/tmp/pip-byVQ07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:06:47 INFO - Running setup.py (path:/tmp/pip-QzVlwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:06:47 INFO - Running setup.py (path:/tmp/pip-Dzyp4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:06:47 INFO - Running setup.py (path:/tmp/pip-YM57zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:06:47 INFO - Running setup.py (path:/tmp/pip-86QQwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:06:47 INFO - Running setup.py (path:/tmp/pip-1pUVRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:06:48 INFO - Running setup.py (path:/tmp/pip-wjJjyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:06:48 INFO - Running setup.py (path:/tmp/pip-7lrC6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:06:48 INFO - Running setup.py (path:/tmp/pip-AT5CoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:06:48 INFO - Running setup.py (path:/tmp/pip-mh8J1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:06:48 INFO - Running setup.py (path:/tmp/pip-yoWnZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:06:48 INFO - Running setup.py (path:/tmp/pip-lnuyQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:06:48 INFO - Running setup.py (path:/tmp/pip-KsxAQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:06:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:06:48 INFO - Running setup.py install for manifestparser 19:06:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:06:48 INFO - Running setup.py install for mozcrash 19:06:48 INFO - Running setup.py install for mozdebug 19:06:48 INFO - Running setup.py install for mozdevice 19:06:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:06:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:06:48 INFO - Running setup.py install for mozfile 19:06:49 INFO - Running setup.py install for mozhttpd 19:06:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Running setup.py install for mozinfo 19:06:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Running setup.py install for mozInstall 19:06:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Running setup.py install for mozleak 19:06:49 INFO - Running setup.py install for mozlog 19:06:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Running setup.py install for moznetwork 19:06:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Running setup.py install for mozprocess 19:06:49 INFO - Running setup.py install for mozprofile 19:06:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:06:49 INFO - Running setup.py install for mozrunner 19:06:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:06:50 INFO - Running setup.py install for mozscreenshot 19:06:50 INFO - Running setup.py install for moztest 19:06:50 INFO - Running setup.py install for mozversion 19:06:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:06:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:06:50 INFO - Cleaning up... 19:06:50 INFO - Return code: 0 19:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:50 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:50 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 19:06:50 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 19:06:50 INFO - Using env: {'DISPLAY': ':0', 19:06:50 INFO - 'HOME': '/home/cltbld', 19:06:50 INFO - 'LANG': 'en_US.UTF-8', 19:06:50 INFO - 'LANGUAGE': 'en_US:en', 19:06:50 INFO - 'LOGNAME': 'cltbld', 19:06:50 INFO - 'MAIL': '/var/mail/cltbld', 19:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:50 INFO - 'MOZ_NO_REMOTE': '1', 19:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:50 INFO - 'NO_EM_RESTART': '1', 19:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:50 INFO - 'PWD': '/builds/slave/test', 19:06:50 INFO - 'SHELL': '/bin/bash', 19:06:50 INFO - 'SHLVL': '1', 19:06:50 INFO - 'TERM': 'linux', 19:06:50 INFO - 'TMOUT': '86400', 19:06:50 INFO - 'USER': 'cltbld', 19:06:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:50 INFO - '_': '/tools/buildbot/bin/python'} 19:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:06:50 INFO - Running setup.py (path:/tmp/pip-gD0SSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:06:50 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)) 19:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:06:50 INFO - Running setup.py (path:/tmp/pip-ZeqD72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:06:50 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)) 19:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:06:50 INFO - Running setup.py (path:/tmp/pip-lQT9X5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:06:50 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)) 19:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:06:50 INFO - Running setup.py (path:/tmp/pip-gBg4it-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:06:50 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)) 19:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:06:50 INFO - Running setup.py (path:/tmp/pip-Pv1WHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:06:50 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)) 19:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:06:50 INFO - Running setup.py (path:/tmp/pip-WjCTUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:06:51 INFO - Running setup.py (path:/tmp/pip-mPx6i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:06:51 INFO - Running setup.py (path:/tmp/pip-X3KahN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:06:51 INFO - Running setup.py (path:/tmp/pip-dk4_dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:06:51 INFO - Running setup.py (path:/tmp/pip-iF4ZWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:06:51 INFO - Running setup.py (path:/tmp/pip-q5JQVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:06:51 INFO - Running setup.py (path:/tmp/pip-4ia5sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:06:51 INFO - Running setup.py (path:/tmp/pip-73lk2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:06:51 INFO - Running setup.py (path:/tmp/pip-NP5VJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:06:51 INFO - Running setup.py (path:/tmp/pip-NgDeCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:06:51 INFO - Running setup.py (path:/tmp/pip-5yLUdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:06:51 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)) 19:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:06:51 INFO - Running setup.py (path:/tmp/pip-Rput7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:06:51 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)) 19:06:51 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)) 19:06:51 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)) 19:06:51 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)) 19:06:51 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)) 19:06:51 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)) 19:06:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:06:51 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 19:06:51 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 19:06:51 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 19:06:51 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 19:06:51 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 19:06:51 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 19:06:53 INFO - Downloading blessings-1.6.tar.gz 19:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:06:53 INFO - Installing collected packages: blessings 19:06:53 INFO - Running setup.py install for blessings 19:06:54 INFO - Successfully installed blessings 19:06:54 INFO - Cleaning up... 19:06:54 INFO - Return code: 0 19:06:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:06:54 INFO - Reading from file tmpfile_stdout 19:06:54 INFO - Current package versions: 19:06:54 INFO - argparse == 1.2.1 19:06:54 INFO - blessings == 1.6 19:06:54 INFO - blobuploader == 1.2.4 19:06:54 INFO - docopt == 0.6.1 19:06:54 INFO - functools32 == 3.2.3-2 19:06:54 INFO - jsonschema == 2.5.1 19:06:54 INFO - manifestparser == 1.1 19:06:54 INFO - mozInstall == 1.12 19:06:54 INFO - mozcrash == 1.0 19:06:54 INFO - mozdebug == 0.1 19:06:54 INFO - mozdevice == 0.49 19:06:54 INFO - mozfile == 1.2 19:06:54 INFO - mozhttpd == 0.7 19:06:54 INFO - mozinfo == 0.9 19:06:54 INFO - mozleak == 0.1 19:06:54 INFO - mozlog == 3.4 19:06:54 INFO - moznetwork == 0.27 19:06:54 INFO - mozprocess == 0.24 19:06:54 INFO - mozprofile == 0.28 19:06:54 INFO - mozrunner == 6.13 19:06:54 INFO - mozscreenshot == 0.1 19:06:54 INFO - mozsystemmonitor == 0.3 19:06:54 INFO - moztest == 0.8 19:06:54 INFO - mozversion == 1.4 19:06:54 INFO - psutil == 3.1.1 19:06:54 INFO - requests == 1.2.3 19:06:54 INFO - wsgiref == 0.1.2 19:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:06:54 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')]} 19:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:54 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')]} 19:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:54 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:54 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 19:06:54 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 19:06:54 INFO - Using env: {'DISPLAY': ':0', 19:06:54 INFO - 'HOME': '/home/cltbld', 19:06:54 INFO - 'LANG': 'en_US.UTF-8', 19:06:54 INFO - 'LANGUAGE': 'en_US:en', 19:06:54 INFO - 'LOGNAME': 'cltbld', 19:06:54 INFO - 'MAIL': '/var/mail/cltbld', 19:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:54 INFO - 'MOZ_NO_REMOTE': '1', 19:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:54 INFO - 'NO_EM_RESTART': '1', 19:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:54 INFO - 'PWD': '/builds/slave/test', 19:06:54 INFO - 'SHELL': '/bin/bash', 19:06:54 INFO - 'SHLVL': '1', 19:06:54 INFO - 'TERM': 'linux', 19:06:54 INFO - 'TMOUT': '86400', 19:06:54 INFO - 'USER': 'cltbld', 19:06:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:54 INFO - '_': '/tools/buildbot/bin/python'} 19:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 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)) 19:06:54 INFO - Cleaning up... 19:06:54 INFO - Return code: 0 19:06:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:06:54 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')]} 19:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:54 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')]} 19:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:06:54 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 0x121c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158e980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', '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 19:06:54 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 19:06:54 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 19:06:54 INFO - Using env: {'DISPLAY': ':0', 19:06:54 INFO - 'HOME': '/home/cltbld', 19:06:54 INFO - 'LANG': 'en_US.UTF-8', 19:06:54 INFO - 'LANGUAGE': 'en_US:en', 19:06:54 INFO - 'LOGNAME': 'cltbld', 19:06:54 INFO - 'MAIL': '/var/mail/cltbld', 19:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:06:54 INFO - 'MOZ_NO_REMOTE': '1', 19:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:06:54 INFO - 'NO_EM_RESTART': '1', 19:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:06:54 INFO - 'PWD': '/builds/slave/test', 19:06:54 INFO - 'SHELL': '/bin/bash', 19:06:54 INFO - 'SHLVL': '1', 19:06:54 INFO - 'TERM': 'linux', 19:06:54 INFO - 'TMOUT': '86400', 19:06:54 INFO - 'USER': 'cltbld', 19:06:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:06:54 INFO - '_': '/tools/buildbot/bin/python'} 19:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:06:54 INFO - Cleaning up... 19:06:54 INFO - Return code: 0 19:06:54 INFO - Running post-action listener: _resource_record_post_action 19:06:54 INFO - Running post-action listener: _start_resource_monitoring 19:06:54 INFO - Starting resource monitoring. 19:06:54 INFO - [mozharness: 2017-02-07 03:06:54.835517Z] Finished create-virtualenv step (success) 19:06:54 INFO - [mozharness: 2017-02-07 03:06:54.836007Z] Running install step. 19:06:54 INFO - Running pre-action listener: _resource_record_pre_action 19:06:54 INFO - Running main action method: install 19:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:06:55 INFO - Reading from file tmpfile_stdout 19:06:55 INFO - Detecting whether we're running mozinstall >=1.0... 19:06:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:06:55 INFO - Reading from file tmpfile_stdout 19:06:55 INFO - Output received: 19:06:55 INFO - Usage: mozinstall [options] installer 19:06:55 INFO - Options: 19:06:55 INFO - -h, --help show this help message and exit 19:06:55 INFO - -d DEST, --destination=DEST 19:06:55 INFO - Directory to install application into. [default: 19:06:55 INFO - "/builds/slave/test"] 19:06:55 INFO - --app=APP Application being installed. [default: firefox] 19:06:55 INFO - mkdir: /builds/slave/test/build/application 19:06:55 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'] 19:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:07:11 INFO - Reading from file tmpfile_stdout 19:07:11 INFO - Output received: 19:07:11 INFO - /builds/slave/test/build/application/firefox/firefox 19:07:11 INFO - Running post-action listener: _resource_record_post_action 19:07:11 INFO - [mozharness: 2017-02-07 03:07:11.626116Z] Finished install step (success) 19:07:11 INFO - [mozharness: 2017-02-07 03:07:11.626400Z] Running run-tests step. 19:07:11 INFO - Running pre-action listener: _resource_record_pre_action 19:07:11 INFO - Running main action method: run_tests 19:07:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:07:11 INFO - Python 2.7.3 19:07:11 INFO - Return code: 0 19:07:11 INFO - grabbing minidump binary from tooltool 19:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:11 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 0x158bd00>, '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 0x158ca00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x158ceb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:07:11 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 19:07:11 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 19:07:11 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 19:07:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:07:11 INFO - Return code: 0 19:07:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:07:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:07:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:07:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:07:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:07:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 19:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 19:07:11 INFO - Using env: {'DISPLAY': ':0', 19:07:11 INFO - 'HOME': '/home/cltbld', 19:07:11 INFO - 'LANG': 'en_US.UTF-8', 19:07:11 INFO - 'LANGUAGE': 'en_US:en', 19:07:11 INFO - 'LOGNAME': 'cltbld', 19:07:11 INFO - 'MAIL': '/var/mail/cltbld', 19:07:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:07:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:11 INFO - 'MOZ_NO_REMOTE': '1', 19:07:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:07:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:11 INFO - 'NO_EM_RESTART': '1', 19:07:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:11 INFO - 'PWD': '/builds/slave/test', 19:07:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:07:11 INFO - 'SHELL': '/bin/bash', 19:07:11 INFO - 'SHLVL': '1', 19:07:11 INFO - 'TERM': 'linux', 19:07:11 INFO - 'TMOUT': '86400', 19:07:11 INFO - 'USER': 'cltbld', 19:07:11 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406', 19:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:11 INFO - '_': '/tools/buildbot/bin/python'} 19:07:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 19:07:11 INFO - mozversion application_buildid: 20170207015822 19:07:11 INFO - mozversion application_changeset: fcc32735c01d504767a0dee94c6bc706b6a23b5e 19:07:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:07:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:07:11 INFO - mozversion application_name: Firefox 19:07:11 INFO - mozversion application_remotingname: firefox-dev 19:07:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:07:11 INFO - mozversion application_vendor: Mozilla 19:07:11 INFO - mozversion application_version: 53.0a2 19:07:11 INFO - mozversion platform_buildid: 20170207015822 19:07:11 INFO - mozversion platform_changeset: fcc32735c01d504767a0dee94c6bc706b6a23b5e 19:07:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:07:11 INFO - mozversion platform_version: 53.0a2 19:07:11 INFO - using testdate: 1486436831 19:07:11 INFO - actual date: 1486436831 19:07:11 INFO - starting webserver on 'localhost:35587' 19:07:11 INFO - SUITE-START | Running 2 tests 19:07:11 INFO - TEST-START | dromaeo_css 19:07:11 INFO - operating with platform_type : linux_ 19:07:11 INFO - Initialising browser for dromaeo_css test... 19:07:11 INFO - TEST-INFO | started process 7679 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2Z3Gqk/profile http://localhost:35587/getInfo.html) 19:07:16 INFO - PROCESS | 7679 | __metrics Screen width/height:1600/1200 19:07:16 INFO - PROCESS | 7679 | colorDepth:24 19:07:16 INFO - PROCESS | 7679 | Browser inner width/height: 1024/697 19:07:16 INFO - PROCESS | 7679 | __metrics 19:07:16 INFO - PROCESS | 7679 | 1486436836766 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:07:16 INFO - PROCESS | 7679 | 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" 19:07:16 INFO - PROCESS | 7679 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:07:17 INFO - PROCESS | 7679 | console.error: PushService: 19:07:17 INFO - PROCESS | 7679 | stateChangeProcessEnqueue: Error transitioning state 19:07:17 INFO - PROCESS | 7679 | UnknownError 19:07:18 INFO - TEST-INFO | 7679: exit 0 19:07:18 INFO - Browser initialized. 19:07:18 INFO - Running cycle 1/1 for dromaeo_css test... 19:07:18 INFO - TEST-INFO | started process 7771 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2Z3Gqk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:29 INFO - PROCESS | 7771 | RSS: Main: 138489856 19:07:29 INFO - PROCESS | 7771 | 19:10:29 INFO - PROCESS | 7771 | Cycle 1(1): loaded http://localhost:35587/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35587/tests/dromaeo/cssquery-ext.html) 19:10:31 INFO - PROCESS | 7771 | RSS: Main: 307671040 19:10:31 INFO - PROCESS | 7771 | 19:12:20 INFO - PROCESS | 7771 | ************************* 19:12:20 INFO - PROCESS | 7771 | A coding exception was thrown and uncaught in a Task. 19:12:20 INFO - PROCESS | 7771 | 19:12:20 ERROR - PROCESS | 7771 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:12:20 INFO - PROCESS | 7771 | Full stack: 19:12:20 INFO - PROCESS | 7771 | ************************* 19:13:33 INFO - PROCESS | 7771 | Cycle 1(1): loaded http://localhost:35587/tests/dromaeo/cssquery-ext.html (next: http://localhost:35587/tests/dromaeo/cssquery-jquery.html) 19:13:34 INFO - PROCESS | 7771 | RSS: Main: 229076992 19:13:34 INFO - PROCESS | 7771 | 19:16:36 INFO - PROCESS | 7771 | Cycle 1(1): loaded http://localhost:35587/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35587/tests/dromaeo/cssquery-mootools.html) 19:16:36 INFO - PROCESS | 7771 | RSS: Main: 200769536 19:16:36 INFO - PROCESS | 7771 | 19:19:38 INFO - PROCESS | 7771 | Cycle 1(1): loaded http://localhost:35587/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35587/tests/dromaeo/cssquery-prototype.html) 19:19:39 INFO - PROCESS | 7771 | RSS: Main: 214523904 19:19:39 INFO - PROCESS | 7771 | 19:22:41 INFO - PROCESS | 7771 | Cycle 1(1): loaded http://localhost:35587/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35587/tests/dromaeo/cssquery-yui.html) 19:22:42 INFO - PROCESS | 7771 | RSS: Main: 168079360 19:22:42 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | Cycle 1(1): loaded http://localhost:35587/tests/dromaeo/cssquery-yui.html (next: http://localhost:35587/tests/dromaeo/cssquery-dojo.html) 19:25:45 INFO - PROCESS | 7771 | RSS: Main: 255889408 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | __start_tp_report 19:25:45 INFO - PROCESS | 7771 | _x_x_mozilla_page_load 19:25:45 INFO - PROCESS | 7771 | _x_x_mozilla_page_load_details 19:25:45 INFO - PROCESS | 7771 | |i|pagename|runs| 19:25:45 INFO - PROCESS | 7771 | |0;dojo.html;3223.903282901513;3260.788048776828;3264.951025734613;3271.5910511186103;3274.296026354334;5413.242146099545;5459.071957767179;5472.905418916218;5472.91790623141;5490.176473528973;5462.535684466825;5510.91733623996;5519.365272993607;5521.917171242435;5534.142207957763;5017.122003649356;5067.467915868818;5071.7210553419545;5079.822206222783;5110.463401342842;4862.173430516803;4870.732109733964;5068.670536415121;5124.180131179012;5130.846074617767;4915.164422048242;4930.3344048553345;4956.67781594197;5009.423916249623;5035.269885866547;5035.622328325372;5045.394552653686;5049.64652474327;5051.898962020776;5083.872903177413;4823.324734537167;4957.479464656231;4971.105201063807;4992.350994370745;5016.147254966664;5322.201669749568;5323.840284791462;5400.864978375533;5403.081476149025;5482.396936337;5817.7382017809305;5839.240898683144;5840.853978650525;5841.153032810219;5855.121731740231;5861.736221870026;5895.970520147414;5918.40815918409;5919.112133180014;5922.97038514809;5341.6527925685095;5391.433899440581;5436.483534064258;5438.347398312228;5468.671879687232;5931.851703707442;5944.910826337608;5953.315368732651;5954.910676339859;5977.402259773988;5648.971755141198;5726.742296596663;5729.48434640876;5738.942610573841;5741.167530708024;5636.408177141423;5638.436156384329;5659.207710920475;5684.914726279109;5812.070068789058;5663.858004686693;5678.176664383724;5712.914306285409;5736.598438109376;5751.051076572318;5730.885382292331;5735.598508104393;5736.254286942754;5742.684152371576;5749.080147176515;4418.80115394808;4435.556444355539;4469.5530446955045;4496.123255965121;4497.977510112429;3938.547067087264;4010.1779135277197;4017.055991841915;4029.2747305485295;4039.4546736190828;5583.078791999355;5587.38538760736;5587.636803607753;5591.9720401398545;5594.692291923983;5619.522340601095;5631.099024156198;5754.856128596819;5770.105633626795;5817.360090390053;5594.580406469531;5597.384287728347;5600.243967064475;5612.635178713371;5627.662340259598;4901.852944411673;4962.627802914723;4963.429205641398;4969.577585905166;5002.424721157041;5897.852553686193;5946.108083787466;5955.523558115254;6037.969810151009;6045.637261764308;5798.275215598051;5882.5293976482635;5888.558358123244;5910.468057874812;5926.170336152856;6088.51291896656;6091.299500557493;6099.603525770811;6101.633901965896;6102.816915492364;5525;5731.083026715873;5785.218995435563;5791.218185545066;5798.710064496674;5670.773169073191;5785.884282314414;5800.332961709458;5842.941570584239;5854.824355269348;5735.7418916148035;5739.7417116229035;5740.0815869460685;5740.885182296414;5743.741531631003;5737.225474560881;5739.311282646109;5741.282339707536;5745.109508026263;5754.108113242454;5434.157705555645;5529.364123125892;5530.391656917655;5606.915896261641;5608.803691870929;5644.096944488862;5644.153376993484;5657.8019769307875;5664.320281566238;5664.546836253007;5610.971945140248;5616.522595579258;5632.380438151882;5643.209950606836;5646.435356464321 19:25:45 INFO - PROCESS | 7771 | |1;ext.html;561.3320149022662;589.8908701890149;628.6672254819781;633.5818359882476;684.0867441964976;36163.457548136765;36677.44983825241;39774;51995.48004519954;53462.73268633661;49569.50430495694;49985.75007124959;50012.49987500124;50103.49896501034;50136.749316253365;9365.67220147295;9585.137337639611;9589.089036541518;9590.520473976308;9592.376495527804;9033.954830225872;9246.583903724348;9257.305702072337;9449.95275023624;11166;11141.72145696356;11505;11561;11569.884301157006;11594.768104637902;11431.828522572127;11457.885421145766;11511;11524.07807375412;11569.016633586156;11484.885151148548;11497.367644779533;11566;11577.594784182513;11580.305181689042;46776.59670209899;47201.29198062032;47230.76384618089;47239;47256.7637161812;4379.693421460499;4441.156180325728;4497.9100417991785;4502.752348620825;4694.413198350207;31962.840185799156;32032;32272.193195170308;33250.833745831354;33294.3341133176;4593.586577208034;4707.199776038082;4753.144434001878;4793.233082706785;4793.3768610080815;345138;348184;348774;350328.738494154;350712;58671;58682;58942;59078.40921590729;59085.70457147773;63548.364516354246;63643.681781591724;63766;63774.681126594995;63863.68068159629;18689;19289.614207715767;19303;19304.32434864773;19309.42071737822;14989;15056.698866022618;15057.849421505864;15059.02116362433;15079.773803392958;17372.47882563525;17800.198991045436;19034.714479282822;19455.67018498475;20268.797312026985;7265.8546829063125;7343.228960959129;7392.852142957111;7485.85028299431;7525.736599219001;987.3483500889379;987.4026214140486;992.5682328187262;993.4635296939716;996.8255555277798;32020.83989580037;32158.03525894227;32726.672733272837;34666;35320.07290909254;1125.707316097804;1132.8300754886832;1133.7505748735266;1146.690393593725;1170.8653504847052;16512.587185320463;16662.750058749127;16778.55671736378;17075.914620427066;17077.658446830992;519733;523804;526351;528383;528448;59880.70059649674;60235.69882150649;60827.39172608306;60855.39144608497;60918.69540652268;1513.9848601514063;1518.9924050379677;1557.0968838074116;1570.1128862192825;1574.0948954351293;29605.703942961158;31562.054743157107;31812.84093579517;31866.68133318637;32347.67652323541;53925;55046.17430738622;55104.448955509935;55180;55201.171982419495;362212;362267;363202;363239;363461;25663.10179143721;29249;29398.853005734836;29605.25986850346;29671.25821854471;41741;46520.53479465162;46673.89967451541;50453.24320135127;51578.74210628923;39768.2046359077;40983.59016409798;41359.09433660736;42169.36745948875;42426.575734242266;39175.60824391834;39822;39916.800415997735;40679.38980915229;41418.792906035276;40085;40424;41136.58863411328;41265.58734412617;41506;33326.66673333236;35494.64505354913;35539;35927.64072359243;36076.639233607326;26911.730882691707;27096.593551097143;27143.1857044289;27491.082758000666;27545.31136721598 19:25:45 INFO - PROCESS | 7771 | |2;jquery.html;426.8506022891987;426.9588536394644;427.32482677369757;437.1890143783284;437.29158762804263;20396.89801550992;21371.358859234228;22398.440038999033;22614.886925565348;22777.316680499593;21652.242171524;21741;21837;21909.233176838814;22673.093076276928;6016.939830601693;6220.253569571635;6223.471004964584;6225.968870155665;6242.719077641495;5790.826275211729;6030.306514750816;6052.303985041711;6060.969695151518;6068.848278793022;5954.404559544054;5982.132590774335;6007.068904319837;6008.549358798085;6014.037753959367;6129.816105516842;6144.631322120665;6158.199434073586;6165.414285642879;6171.105189747506;5438.374586922515;5467.261919640838;5486.780528778844;5560.165975103726;5563.499285064323;26535;26542.6018609721;26551;26638.46723065547;27091.593626095626;2980.0761763853275;3294.5058241264;3392.7285817134816;3432.9313413731584;3490.7730997485082;15823;15975.360985560563;16230.675386492205;16245.431409900715;16258.75611865823;2058.8044135807077;2061.68043953186;2063.7110804487384;2075.5537559424647;2095.758987716417;73342.63328683322;75773;91919.54040229823;93236.53381733048;95385.5230723849;11113.388763617917;11191.160662950311;11214.495347709373;11290;11669.533218671328;14956;14975.400983960737;14983.32575034126;15024.774628380583;15057.096574205363;8834.690785822466;8870.246029087471;8972.551372431351;9006.369554131279;9047.095290470901;8809.295256379473;8824.8676269856;8888.066752990972;9028.277737780962;9070.455772653662;7514;7652.502587331805;7751.883721744179;7779.183185765526;7832.373410127176;1889.7448844406103;1898.411492437349;1943.5238366600274;1946.2117842273901;1974.743283373181;949.3639261694681;954.5942974235921;965.9565319560637;967.8064387122604;974.181687382602;20733.68899466509;22280;22442.214522491635;22450.88774556117;22452;985.0592683986775;987.7481242283171;991.2714155095998;994.4928086675848;994.6916455898695;12067.094967877445;12427.130100892851;12552.623421297376;12777.616671499869;12802.16785908913;586900;591189;592684;599072;600256;95442;95542;97828;98476.50761746289;101453;1124.8537690100397;1143.388287266325;1169.8362229287907;1176.7940610393148;1184.2775906696909;18560.628787423906;18755.156017979498;18978.43064708061;19095.331663391713;19102.713459297844;41977.790111049246;41984.58015419929;45013.32480012737;45157;45363.31955020743;61352.69323653353;61801.690991544754;62456;62750.37249627445;64592;9279.489628070522;9318.347715659838;9348.95325523368;9352.485613291334;9545.52272386364;13550.390232439368;13592.728145437233;13873.657214990424;14011.579652610439;14201.360938757987;12044.096692747904;12076.21504602198;12235.571754988532;12402.379881006094;12794.232346059267;12054.276743395425;12101.152919295564;12147.210431321935;12232.571859984857;12352.814707779571;11679.416029198675;11963.342016189194;12531.87468125307;12603.684907877376;12840.486380545048;7983.600819958862;9332.20676242527;9509.476978765957;9560.521973901137;9769.071938165864;10656.627018054642;12171.45228464739;12209.938950305192;12444.506659200953;12647 19:25:45 INFO - PROCESS | 7771 | |3;mootools.html;1503.5113588083875;1530.8009958705375;1531.1043039821704;1545.165610570292;1554.9844501554985;8719.302455803536;9264.58309376078;9274.53627318633;9280.211182049525;9281.675141370046;7942.7220047298215;8176.672933082685;8199.508029518232;8413.200745929142;9077.228435582983;2331.825113116519;2427.647991041307;2430.428849220432;2443.51129774045;2447.877606119696;2453.6442215878687;2456.238566044523;2467.494163696438;2491.862947537885;2497.2633073243437;2433.537627850703;2440.2313271319567;2464.864432456214;2470.5800013997664;2471.88876500557;2389.713234411873;2403.735589085199;2405.4106743847783;2412.9276121716375;2414.28778510339;2402.9279121626378;2434.087217293515;2435.1842132885326;2479.5784716598223;2479.6776419065586;9235.215006724437;9285.860712089325;9290.628374864997;9293.349465537414;9301.627934882597;5385.542228910547;5525.613207075467;5526.917096243559;5529.612927095105;5529.778808847641;3262.168147122469;3451.2234747181933;3487.4420092785285;3508.964910350915;3540.185757275841;1287.7810772168755;1357.321339330335;1363.5636596289191;1375.9518416855462;1409.6546346145158;11618.883811161864;11631.59289424866;11646.825297620542;11667.241629294154;11688.824667629993;4731.243001119804;4938.333325001079;4999.400071991312;5056;5066.847994560169;10782.245242833083;10798.67603971882;10813.080888124598;10818.24272300947;10828.620998265023;5741;5750.1949727038245;5758.62568933018;5762.279715035621;5762.423757624288;4528.071745292207;5113.71874546896;5187.610929180327;5217.869553261199;5269.71016594091;3785.2051069275763;4171.20747058058;4190.476190476191;4292.59220374064;4309.84915527955;1308.4896890212824;1346.2730125731996;1350.2236214176887;1356.3625096204769;1365.5015919189425;1025.2771795883884;1025.7845852370936;1036.7356324137447;1041.8749750030045;1042.61944390297;5906.320773111147;5942.554308426886;5971.552133589998;6073.18012068374;6100.176476175749;1043.186314674555;1060.1095080132725;1065.6962765611918;1076.0960792933972;1090.0462095666292;3980.1641655252142;3996.660283875845;4006.6193711597352;4006.7395619284653;4036.979815100906;10912.50893709785;11259.718507037389;11261.042811360961;11265.774684506265;11273.71815704614;11091.500882460308;11134.777304454028;11160.665180044612;11162.107031437465;11169.162312826407;8008.439409241297;8338.332933365316;8368.581570921551;8573.571321433903;8639.524826134497;6258.468030217483;6302.117703521602;6428.4214420702365;6428.4214420702365;6523.412892839668;10049.145822604867;10137.695869123938;10242.180625550087;10272.486375681336;10349.74125646865;10313.484325783531;10376;10470.895291046992;10486.737831554272;10509.159267258754;7702.191269916578;7897.605119743875;7961.442699011245;8024.157463466486;8143.633536490754;8767.298616110824;8799.736007919773;8816.779580510538;8829.911700882909;8852.822943540965;7462.552246865205;7632.084149902047;7635.350995165473;7813.10149332834;7882.330001949898;7605.35354494852;7638.274363935418;7657.387409007378;7698.999130113049;7736.7292144774665;6557.606543607399;6603.537752357481;6617.07360969455;6620.172478440195;6654.101696270942;5231.712255825966;5232.947670523246;5327.227552005015;5340.038793017139;5383.7308134593895;5021.271915572149;5078.390593128848;5099.209622508517;5120.820771272987;5142.280080788618 19:25:45 INFO - PROCESS | 7771 | |4;prototype.html;355.68166490990563;372.88999745075216;373.82803910201307;376.3075940269903;382.8985318890494;5371.91942120868;5408.134193703166;5411.540019098381;5417.83746487605;5452.863678408037;5076.720780357079;5213.504717051884;5235.319408476893;5266.394364648067;5293.206019097137;4241.384999175118;4247;4258.382534532491;4263.786810659462;4304.418903448026;4081.9183616327805;4259.74441533509;4272.829086836523;4280.957190428087;4289.6997210195295;4046.9797651011704;4113.71204015719;4226.640735537468;4251.702380833328;4270.274053410928;4227.450431443924;4239.300515414953;4239.96481688788;4258.850940217093;4262.5950534699305;3848.5958974307573;3850.7689538627774;3858.0740622250737;3867.4972253607134;3874.6319099685484;4997.625178111656;5198.974005129988;5202.661826981272;5230.790768369261;5288.550473209782;4720.669553131283;4729.929051064237;4742.644301677388;4747.596454301389;4750.456101766939;5587.7485513152005;5600.971995139998;5602.971985140089;5621.690807005613;5636.577256705764;3905.9804700976597;4005.679545636371;4020.798960051986;4037.656799172074;4066.3900414937702;5750.3962083981;5754.424557544254;5784.537237021028;5796.681182534959;5801.419858014165;4127.2570937231585;4128.60778226074;4134.503859536875;4144.668426525871;4164.8125834337525;4028.1540876415693;4119.331670673382;4182.56083111275;4184.8744537663915;4189.2668782963465;4714.363560919232;4727.314539391838;4753.239481682914;4759.714417134983;4763.4522029967;5612.270404847343;5629.240052592848;5642.6614403135945;5672.319321681342;5692.800751973661;1315.7236980234068;1369.513822592985;1381.2886363522794;1392.7952321242255;1400.43982407038;908.5775114571755;943.42451104826;959.7552624080814;964.2739496301682;972.5088830140734;5301.496357845998;5331.920021199608;5342.706151161723;5363.865903352447;5367.973160134175;5389.353277606712;5531.778728850801;5582.385937546866;5583.4137415571595;5596.804111856066;5253.737313134405;5285.497877701612;5295.399128592517;5311.282976798083;5319.042572337016;2893.654450680444;2896.9855150724534;2907.819135648264;2908.75275601572;2921.7078292171036;5592.406164243172;5679.858003549862;5684.431556844365;5699.515541178963;5878.764849406061;5814.30228372598;5825.38833422493;5840.59115861894;5844.357120716718;5862.530997520188;5966.137597857819;5973.104034394875;5973.49225315853;5974.163617093611;5980.312264089685;5526.176361800676;5703.543716502752;5711.943290491273;5754.165645981226;5762.625429347247;5616;5661.830145095653;5685.971570142123;5692;5694.686792226467;5684.658920464785;5887.3523912370465;5890.440408161217;5919.549710320999;5938.366949089035;4086.6934979877224;4113.732607380511;4114.033202197541;4123.814428350792;4148.958510414857;5809.8547536311935;5811.157382179645;5847.093700476433;5848.824535263949;5860.677662728421;5483.725813709379;5594.188842617879;5616.177945424985;5617.129344951539;5708.6574805510145;5602.691851948182;5603.80386686464;5612.158176273591;5616.887662246814;5617.719114044363;5320.28176196224;5420.945790542202;5506.953678801015;5602.607817452739;5605.495505404533;5526.557875370041;5557.499825015768;5620.634658747168;5630.774769009194;5633.324001119892;5493.505584497414;5498.268045565634;5524.58565607573;5540.864122854725;5559.8610034747835 19:25:45 INFO - PROCESS | 7771 | |5;yui.html;822.7531740477855;839.6515446089873;863.5250612163309;924.026170805741;955.2214944819971;16965.999999999985;18156;18201.18094685739;18329.083545822705;18724;16195.595110122253;16505.257263423144;16645.66708665826;16847.663046739057;17002;828.1966492502278;842.6250318608222;844.3034496540348;846.7756044648173;847.0978407995495;701.4178232067397;720.2329519062208;809.9473534220286;843.9324854011695;847.1486156412797;857.3098655582254;872.3239489395737;872.4459967920385;873;873.3929918706501;863.5423225690395;864.9827003459927;865.4677373415367;865.5801936061006;865.7272959017952;535.1517844216921;550.7769353411875;621.1459243540132;640.6028262477247;641;16402.26189821461;17444.127793610267;17454.650906981915;17455.127243637766;17456.127193640394;14609.853901460956;15026.699466010728;15313.081215127128;15331.773458123324;15826.63744825901;14669.339879705332;14785.704285914222;14882.40266520816;15059.623509412242;15171.772423413657;4757.857264282077;4769.35613692154;4819.951800481986;4830.130576496229;4992.575631071363;83543;84480.3930795028;84765;85181.57409212914;87493;11742.647720568397;11813.704657383461;12030.63908082759;12037.638870833889;12246.265224086404;22817.20139795099;22897.198598048988;23214.53570928572;23228.65157022646;23643.172488962802;8465.153484651486;8540.231379175904;8722.694705685271;8736.825263494822;8771.692990745294;4713.151632706145;4832.734199618984;4957.504249575086;4977.178765503724;4981.028699403654;3935.311320518898;4024.8993775155855;4027.5368332641533;4032.8185231664647;4039.6768258539246;671.1543455246425;673.7709178879106;680.0377465885815;685.8388278754488;697.1146643762394;687.1239170058216;696.5402834129552;697.6372286411038;715.1775458223085;716.7957132217397;11048;11090.5563777448;11133.1039010125;11932.821007684892;12306.692332691755;855.8673405622138;858.5321000054985;872.4067634008808;880.3045593980811;884.6549845560237;4989.201727723546;4996.175631020914;5020.121478741279;5096.796128154906;5180.326557547494;72159.2784072163;75041.24958750451;75056;76280.61859690666;78985.60507197543;59604.403955960755;59881.101783473285;67768.6611566939;69257;69624.30375696179;59583;61090.69454652698;61116.694416528524;61241.100711942934;61586;9595;9897.703068907656;9952.402855828674;10016.198704103508;10054.64808731691;43089.78455107704;43135.568644313156;43175;43402;43517.78241108901;52945.7352713234;53223.73388133034;53349.19976200283;54107.45892541182;54677.179842303194;12587.962065038482;12630.179038362845;12633.431495582905;12645.810312845499;12858.228506289652;14027.789583156056;14165.575032749033;14261;14399.640008999859;14870.256487175815;12429.142964926417;12499.375031248219;12760.553380631633;13120.868791312347;13534.93232533831;14145.858541414455;14193.645158871112;14241.786373204617;14347.210903399995;14531.346089426213;13768.24274664903;13783.51757688476;14046.1572305662;14106.153630782186;14590;11372.886271137182;11758.764824703287;12117.81823272634;12232.816507752568;12358.134930554776;10986.801504987041;11049.72375690582;11106.666799996014;11203.495842687262;11208.55165793359 19:25:45 INFO - PROCESS | 7771 | __end_tp_report 19:25:45 INFO - PROCESS | 7771 | __start_cc_report 19:25:45 INFO - PROCESS | 7771 | _x_x_mozilla_cycle_collect,1468 19:25:45 INFO - PROCESS | 7771 | __end_cc_report 19:25:45 INFO - PROCESS | 7771 | __startTimestamp1486437945452__endTimestamp 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | ------- Summary: start ------- 19:25:45 INFO - PROCESS | 7771 | Number of tests: 6 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | [#0] dojo.html Cycles:165 Average:5416.97 Median:5619.52 stddev:590.67 (10.5%) stddev-sans-first:566.87 19:25:45 INFO - PROCESS | 7771 | Values: 3223.9 3260.8 3265.0 3271.6 3274.3 5413.2 5459.1 5472.9 5472.9 5490.2 5462.5 5510.9 5519.4 5521.9 5534.1 5017.1 5067.5 5071.7 5079.8 5110.5 4862.2 4870.7 5068.7 5124.2 5130.8 4915.2 4930.3 4956.7 5009.4 5035.3 5035.6 5045.4 5049.6 5051.9 5083.9 4823.3 4957.5 4971.1 4992.4 5016.1 5322.2 5323.8 5400.9 5403.1 5482.4 5817.7 5839.2 5840.9 5841.2 5855.1 5861.7 5896.0 5918.4 5919.1 5923.0 5341.7 5391.4 5436.5 5438.3 5468.7 5931.9 5944.9 5953.3 5954.9 5977.4 5649.0 5726.7 5729.5 5738.9 5741.2 5636.4 5638.4 5659.2 5684.9 5812.1 5663.9 5678.2 5712.9 5736.6 5751.1 5730.9 5735.6 5736.3 5742.7 5749.1 4418.8 4435.6 4469.6 4496.1 4498.0 3938.5 4010.2 4017.1 4029.3 4039.5 5583.1 5587.4 5587.6 5592.0 5594.7 5619.5 5631.1 5754.9 5770.1 5817.4 5594.6 5597.4 5600.2 5612.6 5627.7 4901.9 4962.6 4963.4 4969.6 5002.4 5897.9 5946.1 5955.5 6038.0 6045.6 5798.3 5882.5 5888.6 5910.5 5926.2 6088.5 6091.3 6099.6 6101.6 6102.8 5525.0 5731.1 5785.2 5791.2 5798.7 5670.8 5785.9 5800.3 5842.9 5854.8 5735.7 5739.7 5740.1 5740.9 5743.7 5737.2 5739.3 5741.3 5745.1 5754.1 5434.2 5529.4 5530.4 5606.9 5608.8 5644.1 5644.2 5657.8 5664.3 5664.5 5611.0 5616.5 5632.4 5643.2 5646.4 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | [#1] ext.html Cycles:180 Average:58847.17 Median:39798.00 stddev:110937.00 (278.8%) stddev-sans-first:111161.90 19:25:45 INFO - PROCESS | 7771 | Values: 561.3 589.9 628.7 633.6 684.1 36163.5 36677.4 39774.0 51995.5 53462.7 49569.5 49985.8 50012.5 50103.5 50136.7 9365.7 9585.1 9589.1 9590.5 9592.4 9034.0 9246.6 9257.3 9450.0 11166.0 11141.7 11505.0 11561.0 11569.9 11594.8 11431.8 11457.9 11511.0 11524.1 11569.0 11484.9 11497.4 11566.0 11577.6 11580.3 46776.6 47201.3 47230.8 47239.0 47256.8 4379.7 4441.2 4497.9 4502.8 4694.4 31962.8 32032.0 32272.2 33250.8 33294.3 4593.6 4707.2 4753.1 4793.2 4793.4 345138.0 348184.0 348774.0 350328.7 350712.0 58671.0 58682.0 58942.0 59078.4 59085.7 63548.4 63643.7 63766.0 63774.7 63863.7 18689.0 19289.6 19303.0 19304.3 19309.4 14989.0 15056.7 15057.8 15059.0 15079.8 17372.5 17800.2 19034.7 19455.7 20268.8 7265.9 7343.2 7392.9 7485.9 7525.7 987.3 987.4 992.6 993.5 996.8 32020.8 32158.0 32726.7 34666.0 35320.1 1125.7 1132.8 1133.8 1146.7 1170.9 16512.6 16662.8 16778.6 17075.9 17077.7 519733.0 523804.0 526351.0 528383.0 528448.0 59880.7 60235.7 60827.4 60855.4 60918.7 1514.0 1519.0 1557.1 1570.1 1574.1 29605.7 31562.1 31812.8 31866.7 32347.7 53925.0 55046.2 55104.4 55180.0 55201.2 362212.0 362267.0 363202.0 363239.0 363461.0 25663.1 29249.0 29398.9 29605.3 29671.3 41741.0 46520.5 46673.9 50453.2 51578.7 39768.2 40983.6 41359.1 42169.4 42426.6 39175.6 39822.0 39916.8 40679.4 41418.8 40085.0 40424.0 41136.6 41265.6 41506.0 33326.7 35494.6 35539.0 35927.6 36076.6 26911.7 27096.6 27143.2 27491.1 27545.3 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | [#2] jquery.html Cycles:180 Average:33283.16 Median:22734.28 stddev:97533.34 (429.0%) stddev-sans-first:97775.75 19:25:45 INFO - PROCESS | 7771 | Values: 426.9 427.0 427.3 437.2 437.3 20396.9 21371.4 22398.4 22614.9 22777.3 21652.2 21741.0 21837.0 21909.2 22673.1 6016.9 6220.3 6223.5 6226.0 6242.7 5790.8 6030.3 6052.3 6061.0 6068.8 5954.4 5982.1 6007.1 6008.5 6014.0 6129.8 6144.6 6158.2 6165.4 6171.1 5438.4 5467.3 5486.8 5560.2 5563.5 26535.0 26542.6 26551.0 26638.5 27091.6 2980.1 3294.5 3392.7 3432.9 3490.8 15823.0 15975.4 16230.7 16245.4 16258.8 2058.8 2061.7 2063.7 2075.6 2095.8 73342.6 75773.0 91919.5 93236.5 95385.5 11113.4 11191.2 11214.5 11290.0 11669.5 14956.0 14975.4 14983.3 15024.8 15057.1 8834.7 8870.2 8972.6 9006.4 9047.1 8809.3 8824.9 8888.1 9028.3 9070.5 7514.0 7652.5 7751.9 7779.2 7832.4 1889.7 1898.4 1943.5 1946.2 1974.7 949.4 954.6 966.0 967.8 974.2 20733.7 22280.0 22442.2 22450.9 22452.0 985.1 987.7 991.3 994.5 994.7 12067.1 12427.1 12552.6 12777.6 12802.2 586900.0 591189.0 592684.0 599072.0 600256.0 95442.0 95542.0 97828.0 98476.5 101453.0 1124.9 1143.4 1169.8 1176.8 1184.3 18560.6 18755.2 18978.4 19095.3 19102.7 41977.8 41984.6 45013.3 45157.0 45363.3 61352.7 61801.7 62456.0 62750.4 64592.0 9279.5 9318.3 9349.0 9352.5 9545.5 13550.4 13592.7 13873.7 14011.6 14201.4 12044.1 12076.2 12235.6 12402.4 12794.2 12054.3 12101.2 12147.2 12232.6 12352.8 11679.4 11963.3 12531.9 12603.7 12840.5 7983.6 9332.2 9509.5 9560.5 9769.1 10656.6 12171.5 12209.9 12444.5 12647.0 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | [#3] mootools.html Cycles:180 Average:5932.04 Median:4104.09 stddev:3296.79 (80.3%) stddev-sans-first:3289.24 19:25:45 INFO - PROCESS | 7771 | Values: 1503.5 1530.8 1531.1 1545.2 1555.0 8719.3 9264.6 9274.5 9280.2 9281.7 7942.7 8176.7 8199.5 8413.2 9077.2 2331.8 2427.6 2430.4 2443.5 2447.9 2453.6 2456.2 2467.5 2491.9 2497.3 2433.5 2440.2 2464.9 2470.6 2471.9 2389.7 2403.7 2405.4 2412.9 2414.3 2402.9 2434.1 2435.2 2479.6 2479.7 9235.2 9285.9 9290.6 9293.3 9301.6 5385.5 5525.6 5526.9 5529.6 5529.8 3262.2 3451.2 3487.4 3509.0 3540.2 1287.8 1357.3 1363.6 1376.0 1409.7 11618.9 11631.6 11646.8 11667.2 11688.8 4731.2 4938.3 4999.4 5056.0 5066.8 10782.2 10798.7 10813.1 10818.2 10828.6 5741.0 5750.2 5758.6 5762.3 5762.4 4528.1 5113.7 5187.6 5217.9 5269.7 3785.2 4171.2 4190.5 4292.6 4309.8 1308.5 1346.3 1350.2 1356.4 1365.5 1025.3 1025.8 1036.7 1041.9 1042.6 5906.3 5942.6 5971.6 6073.2 6100.2 1043.2 1060.1 1065.7 1076.1 1090.0 3980.2 3996.7 4006.6 4006.7 4037.0 10912.5 11259.7 11261.0 11265.8 11273.7 11091.5 11134.8 11160.7 11162.1 11169.2 8008.4 8338.3 8368.6 8573.6 8639.5 6258.5 6302.1 6428.4 6428.4 6523.4 10049.1 10137.7 10242.2 10272.5 10349.7 10313.5 10376.0 10470.9 10486.7 10509.2 7702.2 7897.6 7961.4 8024.2 8143.6 8767.3 8799.7 8816.8 8829.9 8852.8 7462.6 7632.1 7635.4 7813.1 7882.3 7605.4 7638.3 7657.4 7699.0 7736.7 6557.6 6603.5 6617.1 6620.2 6654.1 5231.7 5232.9 5327.2 5340.0 5383.7 5021.3 5078.4 5099.2 5120.8 5142.3 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | [#4] prototype.html Cycles:180 Average:4713.86 Median:5369.95 stddev:1376.40 (25.6%) stddev-sans-first:1340.83 19:25:45 INFO - PROCESS | 7771 | Values: 355.7 372.9 373.8 376.3 382.9 5371.9 5408.1 5411.5 5417.8 5452.9 5076.7 5213.5 5235.3 5266.4 5293.2 4241.4 4247.0 4258.4 4263.8 4304.4 4081.9 4259.7 4272.8 4281.0 4289.7 4047.0 4113.7 4226.6 4251.7 4270.3 4227.5 4239.3 4240.0 4258.9 4262.6 3848.6 3850.8 3858.1 3867.5 3874.6 4997.6 5199.0 5202.7 5230.8 5288.6 4720.7 4729.9 4742.6 4747.6 4750.5 5587.7 5601.0 5603.0 5621.7 5636.6 3906.0 4005.7 4020.8 4037.7 4066.4 5750.4 5754.4 5784.5 5796.7 5801.4 4127.3 4128.6 4134.5 4144.7 4164.8 4028.2 4119.3 4182.6 4184.9 4189.3 4714.4 4727.3 4753.2 4759.7 4763.5 5612.3 5629.2 5642.7 5672.3 5692.8 1315.7 1369.5 1381.3 1392.8 1400.4 908.6 943.4 959.8 964.3 972.5 5301.5 5331.9 5342.7 5363.9 5368.0 5389.4 5531.8 5582.4 5583.4 5596.8 5253.7 5285.5 5295.4 5311.3 5319.0 2893.7 2897.0 2907.8 2908.8 2921.7 5592.4 5679.9 5684.4 5699.5 5878.8 5814.3 5825.4 5840.6 5844.4 5862.5 5966.1 5973.1 5973.5 5974.2 5980.3 5526.2 5703.5 5711.9 5754.2 5762.6 5616.0 5661.8 5686.0 5692.0 5694.7 5684.7 5887.4 5890.4 5919.5 5938.4 4086.7 4113.7 4114.0 4123.8 4149.0 5809.9 5811.2 5847.1 5848.8 5860.7 5483.7 5594.2 5616.2 5617.1 5708.7 5602.7 5603.8 5612.2 5616.9 5617.7 5320.3 5420.9 5507.0 5602.6 5605.5 5526.6 5557.5 5620.6 5630.8 5633.3 5493.5 5498.3 5524.6 5540.9 5559.9 19:25:45 INFO - PROCESS | 7771 | 19:25:45 INFO - PROCESS | 7771 | [#5] yui.html Cycles:180 Average:18004.33 Median:4825.04 stddev:22206.74 (460.2%) stddev-sans-first:22231.56 19:25:45 INFO - PROCESS | 7771 | Values: 822.8 839.7 863.5 924.0 955.2 16966.0 18156.0 18201.2 18329.1 18724.0 16195.6 16505.3 16645.7 16847.7 17002.0 828.2 842.6 844.3 846.8 847.1 701.4 720.2 809.9 843.9 847.1 857.3 872.3 872.4 873.0 873.4 863.5 865.0 865.5 865.6 865.7 535.2 550.8 621.1 640.6 641.0 16402.3 17444.1 17454.7 17455.1 17456.1 14609.9 15026.7 15313.1 15331.8 15826.6 14669.3 14785.7 14882.4 15059.6 15171.8 4757.9 4769.4 4820.0 4830.1 4992.6 83543.0 84480.4 84765.0 85181.6 87493.0 11742.6 11813.7 12030.6 12037.6 12246.3 22817.2 22897.2 23214.5 23228.7 23643.2 8465.2 8540.2 8722.7 8736.8 8771.7 4713.2 4832.7 4957.5 4977.2 4981.0 3935.3 4024.9 4027.5 4032.8 4039.7 671.2 673.8 680.0 685.8 697.1 687.1 696.5 697.6 715.2 716.8 11048.0 11090.6 11133.1 11932.8 12306.7 855.9 858.5 872.4 880.3 884.7 4989.2 4996.2 5020.1 5096.8 5180.3 72159.3 75041.2 75056.0 76280.6 78985.6 59604.4 59881.1 67768.7 69257.0 69624.3 59583.0 61090.7 61116.7 61241.1 61586.0 9595.0 9897.7 9952.4 10016.2 10054.6 43089.8 43135.6 43175.0 43402.0 43517.8 52945.7 53223.7 53349.2 54107.5 54677.2 12588.0 12630.2 12633.4 12645.8 12858.2 14027.8 14165.6 14261.0 14399.6 14870.3 12429.1 12499.4 12760.6 13120.9 13534.9 14145.9 14193.6 14241.8 14347.2 14531.3 13768.2 13783.5 14046.2 14106.2 14590.0 11372.9 11758.8 12117.8 12232.8 12358.1 10986.8 11049.7 11106.7 11203.5 11208.6 19:25:45 INFO - PROCESS | 7771 | -------- Summary: end -------- 19:25:45 INFO - PROCESS | 7771 | 19:25:46 INFO - TEST-INFO | 7771: exit 0 19:25:46 INFO - TEST-OK | dromaeo_css | took 1114327ms 19:25:46 INFO - TEST-START | kraken 19:25:46 INFO - operating with platform_type : linux_ 19:25:46 INFO - Initialising browser for kraken test... 19:25:46 INFO - TEST-INFO | started process 8087 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp22AED8/profile http://localhost:35587/getInfo.html) 19:25:50 INFO - PROCESS | 8087 | __metrics Screen width/height:1600/1200 19:25:50 INFO - PROCESS | 8087 | colorDepth:24 19:25:50 INFO - PROCESS | 8087 | Browser inner width/height: 1024/697 19:25:50 INFO - PROCESS | 8087 | __metrics 19:25:50 INFO - PROCESS | 8087 | 1486437950892 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:25:51 INFO - PROCESS | 8087 | 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" 19:25:51 INFO - PROCESS | 8087 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:25:51 INFO - PROCESS | 8087 | console.error: PushService: 19:25:51 INFO - PROCESS | 8087 | stateChangeProcessEnqueue: Error transitioning state 19:25:51 INFO - PROCESS | 8087 | UnknownError 19:25:52 INFO - TEST-INFO | 8087: exit 0 19:25:52 INFO - Browser initialized. 19:25:52 INFO - Running cycle 1/1 for kraken test... 19:25:52 INFO - TEST-INFO | started process 8182 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp22AED8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:26:04 INFO - PROCESS | 8182 | RSS: Main: 138158080 19:26:04 INFO - PROCESS | 8182 | 19:26:06 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35587/tests/kraken/driver.html?testName=audio-beat-detection) 19:26:07 INFO - PROCESS | 8182 | RSS: Main: 181886976 19:26:07 INFO - PROCESS | 8182 | 19:26:09 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35587/tests/kraken/driver.html?testName=audio-dft) 19:26:10 INFO - PROCESS | 8182 | RSS: Main: 171905024 19:26:10 INFO - PROCESS | 8182 | 19:26:13 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35587/tests/kraken/driver.html?testName=audio-fft) 19:26:13 INFO - PROCESS | 8182 | RSS: Main: 263880704 19:26:13 INFO - PROCESS | 8182 | 19:26:16 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35587/tests/kraken/driver.html?testName=audio-oscillator) 19:26:16 INFO - PROCESS | 8182 | RSS: Main: 170004480 19:26:16 INFO - PROCESS | 8182 | 19:26:18 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35587/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:26:19 INFO - PROCESS | 8182 | RSS: Main: 172830720 19:26:19 INFO - PROCESS | 8182 | 19:26:22 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35587/tests/kraken/driver.html?testName=imaging-darkroom) 19:26:22 INFO - PROCESS | 8182 | RSS: Main: 211050496 19:26:22 INFO - PROCESS | 8182 | 19:26:26 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35587/tests/kraken/driver.html?testName=imaging-desaturate) 19:26:26 INFO - PROCESS | 8182 | RSS: Main: 209854464 19:26:26 INFO - PROCESS | 8182 | 19:26:29 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35587/tests/kraken/driver.html?testName=json-parse-financial) 19:26:30 INFO - PROCESS | 8182 | RSS: Main: 208306176 19:26:30 INFO - PROCESS | 8182 | 19:26:31 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35587/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:26:32 INFO - PROCESS | 8182 | RSS: Main: 170549248 19:26:32 INFO - PROCESS | 8182 | 19:26:33 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:26:34 INFO - PROCESS | 8182 | RSS: Main: 177139712 19:26:34 INFO - PROCESS | 8182 | 19:26:36 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:26:37 INFO - PROCESS | 8182 | RSS: Main: 186417152 19:26:37 INFO - PROCESS | 8182 | 19:26:39 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:26:40 INFO - PROCESS | 8182 | RSS: Main: 196755456 19:26:40 INFO - PROCESS | 8182 | 19:26:43 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:26:43 INFO - PROCESS | 8182 | RSS: Main: 193425408 19:26:43 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | Cycle 1(1): loaded http://localhost:35587/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35587/tests/kraken/driver.html?testName=ai-astar) 19:26:45 INFO - PROCESS | 8182 | RSS: Main: 244563968 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | __start_tp_report 19:26:45 INFO - PROCESS | 8182 | _x_x_mozilla_page_load 19:26:45 INFO - PROCESS | 8182 | _x_x_mozilla_page_load_details 19:26:45 INFO - PROCESS | 8182 | |i|pagename|runs| 19:26:45 INFO - PROCESS | 8182 | |0;ai-astar;111;111;112;111;111;112;111;110;110;110 19:26:45 INFO - PROCESS | 8182 | |1;audio-beat-detection;148;119;119;153;123;119;119;116;151;119 19:26:45 INFO - PROCESS | 8182 | |2;audio-dft;160;162;160;160;160;161;160;162;160;161 19:26:45 INFO - PROCESS | 8182 | |3;audio-fft;75;76;76;112;75;75;75;76;91;75 19:26:45 INFO - PROCESS | 8182 | |4;audio-oscillator;82;83;113;83;82;82;93;82;82;105 19:26:45 INFO - PROCESS | 8182 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 19:26:45 INFO - PROCESS | 8182 | |6;imaging-darkroom;148;148;147;147;147;147;147;147;147;146 19:26:45 INFO - PROCESS | 8182 | |7;imaging-desaturate;96;96;96;96;96;96;96;96;95;96 19:26:45 INFO - PROCESS | 8182 | |8;json-parse-financial;49;53;49;48;48;48;47;48;48;48 19:26:45 INFO - PROCESS | 8182 | |9;json-stringify-tinderbox;63;64;64;64;65;64;64;64;65;65 19:26:45 INFO - PROCESS | 8182 | |10;stanford-crypto-aes;76;76;78;79;77;78;78;75;75;75 19:26:45 INFO - PROCESS | 8182 | |11;stanford-crypto-ccm;125;122;126;131;121;123;120;127;157;123 19:26:45 INFO - PROCESS | 8182 | |12;stanford-crypto-pbkdf2;160;161;163;163;158;164;163;160;162;159 19:26:45 INFO - PROCESS | 8182 | |13;stanford-crypto-sha256-iterative;62;61;61;62;60;61;61;60;60;61 19:26:45 INFO - PROCESS | 8182 | __end_tp_report 19:26:45 INFO - PROCESS | 8182 | __start_cc_report 19:26:45 INFO - PROCESS | 8182 | _x_x_mozilla_cycle_collect,1535 19:26:45 INFO - PROCESS | 8182 | __end_cc_report 19:26:45 INFO - PROCESS | 8182 | __startTimestamp1486438005555__endTimestamp 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | ------- Summary: start ------- 19:26:45 INFO - PROCESS | 8182 | Number of tests: 14 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 19:26:45 INFO - PROCESS | 8182 | Values: 111.0 111.0 112.0 111.0 111.0 112.0 111.0 110.0 110.0 110.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:121.00 stddev:15.36 (12.7%) stddev-sans-first:14.60 19:26:45 INFO - PROCESS | 8182 | Values: 148.0 119.0 119.0 153.0 123.0 119.0 119.0 116.0 151.0 119.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 19:26:45 INFO - PROCESS | 8182 | Values: 160.0 162.0 160.0 160.0 160.0 161.0 160.0 162.0 160.0 161.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.50 stddev:12.08 (16.0%) stddev-sans-first:12.65 19:26:45 INFO - PROCESS | 8182 | Values: 75.0 76.0 76.0 112.0 75.0 75.0 75.0 76.0 91.0 75.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:82.00 stddev:11.37 (13.9%) stddev-sans-first:11.80 19:26:45 INFO - PROCESS | 8182 | Values: 82.0 83.0 113.0 83.0 82.0 82.0 93.0 82.0 82.0 105.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:26:45 INFO - PROCESS | 8182 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 19:26:45 INFO - PROCESS | 8182 | Values: 148.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 146.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:26:45 INFO - PROCESS | 8182 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#8] json-parse-financial Cycles:10 Average:48.60 Median:48.00 stddev:1.65 (3.4%) stddev-sans-first:1.74 19:26:45 INFO - PROCESS | 8182 | Values: 49.0 53.0 49.0 48.0 48.0 48.0 47.0 48.0 48.0 48.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 19:26:45 INFO - PROCESS | 8182 | Values: 63.0 64.0 64.0 64.0 65.0 64.0 64.0 64.0 65.0 65.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:77.50 stddev:1.49 (1.9%) stddev-sans-first:1.56 19:26:45 INFO - PROCESS | 8182 | Values: 76.0 76.0 78.0 79.0 77.0 78.0 78.0 75.0 75.0 75.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:125.50 stddev:10.86 (8.6%) stddev-sans-first:11.48 19:26:45 INFO - PROCESS | 8182 | Values: 125.0 122.0 126.0 131.0 121.0 123.0 120.0 127.0 157.0 123.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:162.50 stddev:2.00 (1.2%) stddev-sans-first:2.07 19:26:45 INFO - PROCESS | 8182 | Values: 160.0 161.0 163.0 163.0 158.0 164.0 163.0 160.0 162.0 159.0 19:26:45 INFO - PROCESS | 8182 | 19:26:45 INFO - PROCESS | 8182 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 19:26:45 INFO - PROCESS | 8182 | Values: 62.0 61.0 61.0 62.0 60.0 61.0 61.0 60.0 60.0 61.0 19:26:45 INFO - PROCESS | 8182 | -------- Summary: end -------- 19:26:45 INFO - PROCESS | 8182 | 19:26:46 INFO - TEST-INFO | 8182: exit 0 19:26:46 INFO - TEST-OK | kraken | took 60382ms 19:26:46 INFO - SUITE-END | took 1174s 19:26:46 INFO - Completed test suite (00:19:34) 19:26:46 INFO - javascript benchmark 19:26:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3223.903282901513, 3260.788048776828, 3264.951025734613, 3271.5910511186103, 3274.296026354334, 5413.242146099545, 5459.071957767179, 5472.905418916218, 5472.91790623141, 5490.176473528973, 5462.535684466825, 5510.91733623996, 5519.365272993607, 5521.917171242435, 5534.142207957763, 5017.122003649356, 5067.467915868818, 5071.7210553419545, 5079.822206222783, 5110.463401342842, 4862.173430516803, 4870.732109733964, 5068.670536415121, 5124.180131179012, 5130.846074617767, 4915.164422048242, 4930.3344048553345, 4956.67781594197, 5009.423916249623, 5035.269885866547, 5035.622328325372, 5045.394552653686, 5049.64652474327, 5051.898962020776, 5083.872903177413, 4823.324734537167, 4957.479464656231, 4971.105201063807, 4992.350994370745, 5016.147254966664, 5322.201669749568, 5323.840284791462, 5400.864978375533, 5403.081476149025, 5482.396936337, 5817.7382017809305, 5839.240898683144, 5840.853978650525, 5841.153032810219, 5855.121731740231, 5861.736221870026, 5895.970520147414, 5918.40815918409, 5919.112133180014, 5922.97038514809, 5341.6527925685095, 5391.433899440581, 5436.483534064258, 5438.347398312228, 5468.671879687232, 5931.851703707442, 5944.910826337608, 5953.315368732651, 5954.910676339859, 5977.402259773988, 5648.971755141198, 5726.742296596663, 5729.48434640876, 5738.942610573841, 5741.167530708024, 5636.408177141423, 5638.436156384329, 5659.207710920475, 5684.914726279109, 5812.070068789058, 5663.858004686693, 5678.176664383724, 5712.914306285409, 5736.598438109376, 5751.051076572318, 5730.885382292331, 5735.598508104393, 5736.254286942754, 5742.684152371576, 5749.080147176515, 4418.80115394808, 4435.556444355539, 4469.5530446955045, 4496.123255965121, 4497.977510112429, 3938.547067087264, 4010.1779135277197, 4017.055991841915, 4029.2747305485295, 4039.4546736190828, 5583.078791999355, 5587.38538760736, 5587.636803607753, 5591.9720401398545, 5594.692291923983, 5619.522340601095, 5631.099024156198, 5754.856128596819, 5770.105633626795, 5817.360090390053, 5594.580406469531, 5597.384287728347, 5600.243967064475, 5612.635178713371, 5627.662340259598, 4901.852944411673, 4962.627802914723, 4963.429205641398, 4969.577585905166, 5002.424721157041, 5897.852553686193, 5946.108083787466, 5955.523558115254, 6037.969810151009, 6045.637261764308, 5798.275215598051, 5882.5293976482635, 5888.558358123244, 5910.468057874812, 5926.170336152856, 6088.51291896656, 6091.299500557493, 6099.603525770811, 6101.633901965896, 6102.816915492364, 5525.0, 5731.083026715873, 5785.218995435563, 5791.218185545066, 5798.710064496674, 5670.773169073191, 5785.884282314414, 5800.332961709458, 5842.941570584239, 5854.824355269348, 5735.7418916148035, 5739.7417116229035, 5740.0815869460685, 5740.885182296414, 5743.741531631003, 5737.225474560881, 5739.311282646109, 5741.282339707536, 5745.109508026263, 5754.108113242454, 5434.157705555645, 5529.364123125892, 5530.391656917655, 5606.915896261641, 5608.803691870929, 5644.096944488862, 5644.153376993484, 5657.8019769307875, 5664.320281566238, 5664.546836253007, 5610.971945140248, 5616.522595579258, 5632.380438151882, 5643.209950606836, 5646.435356464321], "value": 5379.006935859818, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.3320149022662, 589.8908701890149, 628.6672254819781, 633.5818359882476, 684.0867441964976, 36163.457548136765, 36677.44983825241, 39774.0, 51995.48004519954, 53462.73268633661, 49569.50430495694, 49985.75007124959, 50012.49987500124, 50103.49896501034, 50136.749316253365, 9365.67220147295, 9585.137337639611, 9589.089036541518, 9590.520473976308, 9592.376495527804, 9033.954830225872, 9246.583903724348, 9257.305702072337, 9449.95275023624, 11166.0, 11141.72145696356, 11505.0, 11561.0, 11569.884301157006, 11594.768104637902, 11431.828522572127, 11457.885421145766, 11511.0, 11524.07807375412, 11569.016633586156, 11484.885151148548, 11497.367644779533, 11566.0, 11577.594784182513, 11580.305181689042, 46776.59670209899, 47201.29198062032, 47230.76384618089, 47239.0, 47256.7637161812, 4379.693421460499, 4441.156180325728, 4497.9100417991785, 4502.752348620825, 4694.413198350207, 31962.840185799156, 32032.0, 32272.193195170308, 33250.833745831354, 33294.3341133176, 4593.586577208034, 4707.199776038082, 4753.144434001878, 4793.233082706785, 4793.3768610080815, 345138.0, 348184.0, 348774.0, 350328.738494154, 350712.0, 58671.0, 58682.0, 58942.0, 59078.40921590729, 59085.70457147773, 63548.364516354246, 63643.681781591724, 63766.0, 63774.681126594995, 63863.68068159629, 18689.0, 19289.614207715767, 19303.0, 19304.32434864773, 19309.42071737822, 14989.0, 15056.698866022618, 15057.849421505864, 15059.02116362433, 15079.773803392958, 17372.47882563525, 17800.198991045436, 19034.714479282822, 19455.67018498475, 20268.797312026985, 7265.8546829063125, 7343.228960959129, 7392.852142957111, 7485.85028299431, 7525.736599219001, 987.3483500889379, 987.4026214140486, 992.5682328187262, 993.4635296939716, 996.8255555277798, 32020.83989580037, 32158.03525894227, 32726.672733272837, 34666.0, 35320.07290909254, 1125.707316097804, 1132.8300754886832, 1133.7505748735266, 1146.690393593725, 1170.8653504847052, 16512.587185320463, 16662.750058749127, 16778.55671736378, 17075.914620427066, 17077.658446830992, 519733.0, 523804.0, 526351.0, 528383.0, 528448.0, 59880.70059649674, 60235.69882150649, 60827.39172608306, 60855.39144608497, 60918.69540652268, 1513.9848601514063, 1518.9924050379677, 1557.0968838074116, 1570.1128862192825, 1574.0948954351293, 29605.703942961158, 31562.054743157107, 31812.84093579517, 31866.68133318637, 32347.67652323541, 53925.0, 55046.17430738622, 55104.448955509935, 55180.0, 55201.171982419495, 362212.0, 362267.0, 363202.0, 363239.0, 363461.0, 25663.10179143721, 29249.0, 29398.853005734836, 29605.25986850346, 29671.25821854471, 41741.0, 46520.53479465162, 46673.89967451541, 50453.24320135127, 51578.74210628923, 39768.2046359077, 40983.59016409798, 41359.09433660736, 42169.36745948875, 42426.575734242266, 39175.60824391834, 39822.0, 39916.800415997735, 40679.38980915229, 41418.792906035276, 40085.0, 40424.0, 41136.58863411328, 41265.58734412617, 41506.0, 33326.66673333236, 35494.64505354913, 35539.0, 35927.64072359243, 36076.639233607326, 26911.730882691707, 27096.593551097143, 27143.1857044289, 27491.082758000666, 27545.31136721598], "value": 21207.159010436284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.8506022891987, 426.9588536394644, 427.32482677369757, 437.1890143783284, 437.29158762804263, 20396.89801550992, 21371.358859234228, 22398.440038999033, 22614.886925565348, 22777.316680499593, 21652.242171524, 21741.0, 21837.0, 21909.233176838814, 22673.093076276928, 6016.939830601693, 6220.253569571635, 6223.471004964584, 6225.968870155665, 6242.719077641495, 5790.826275211729, 6030.306514750816, 6052.303985041711, 6060.969695151518, 6068.848278793022, 5954.404559544054, 5982.132590774335, 6007.068904319837, 6008.549358798085, 6014.037753959367, 6129.816105516842, 6144.631322120665, 6158.199434073586, 6165.414285642879, 6171.105189747506, 5438.374586922515, 5467.261919640838, 5486.780528778844, 5560.165975103726, 5563.499285064323, 26535.0, 26542.6018609721, 26551.0, 26638.46723065547, 27091.593626095626, 2980.0761763853275, 3294.5058241264, 3392.7285817134816, 3432.9313413731584, 3490.7730997485082, 15823.0, 15975.360985560563, 16230.675386492205, 16245.431409900715, 16258.75611865823, 2058.8044135807077, 2061.68043953186, 2063.7110804487384, 2075.5537559424647, 2095.758987716417, 73342.63328683322, 75773.0, 91919.54040229823, 93236.53381733048, 95385.5230723849, 11113.388763617917, 11191.160662950311, 11214.495347709373, 11290.0, 11669.533218671328, 14956.0, 14975.400983960737, 14983.32575034126, 15024.774628380583, 15057.096574205363, 8834.690785822466, 8870.246029087471, 8972.551372431351, 9006.369554131279, 9047.095290470901, 8809.295256379473, 8824.8676269856, 8888.066752990972, 9028.277737780962, 9070.455772653662, 7514.0, 7652.502587331805, 7751.883721744179, 7779.183185765526, 7832.373410127176, 1889.7448844406103, 1898.411492437349, 1943.5238366600274, 1946.2117842273901, 1974.743283373181, 949.3639261694681, 954.5942974235921, 965.9565319560637, 967.8064387122604, 974.181687382602, 20733.68899466509, 22280.0, 22442.214522491635, 22450.88774556117, 22452.0, 985.0592683986775, 987.7481242283171, 991.2714155095998, 994.4928086675848, 994.6916455898695, 12067.094967877445, 12427.130100892851, 12552.623421297376, 12777.616671499869, 12802.16785908913, 586900.0, 591189.0, 592684.0, 599072.0, 600256.0, 95442.0, 95542.0, 97828.0, 98476.50761746289, 101453.0, 1124.8537690100397, 1143.388287266325, 1169.8362229287907, 1176.7940610393148, 1184.2775906696909, 18560.628787423906, 18755.156017979498, 18978.43064708061, 19095.331663391713, 19102.713459297844, 41977.790111049246, 41984.58015419929, 45013.32480012737, 45157.0, 45363.31955020743, 61352.69323653353, 61801.690991544754, 62456.0, 62750.37249627445, 64592.0, 9279.489628070522, 9318.347715659838, 9348.95325523368, 9352.485613291334, 9545.52272386364, 13550.390232439368, 13592.728145437233, 13873.657214990424, 14011.579652610439, 14201.360938757987, 12044.096692747904, 12076.21504602198, 12235.571754988532, 12402.379881006094, 12794.232346059267, 12054.276743395425, 12101.152919295564, 12147.210431321935, 12232.571859984857, 12352.814707779571, 11679.416029198675, 11963.342016189194, 12531.87468125307, 12603.684907877376, 12840.486380545048, 7983.600819958862, 9332.20676242527, 9509.476978765957, 9560.521973901137, 9769.071938165864, 10656.627018054642, 12171.45228464739, 12209.938950305192, 12444.506659200953, 12647.0], "value": 10189.74850358899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.5113588083875, 1530.8009958705375, 1531.1043039821704, 1545.165610570292, 1554.9844501554985, 8719.302455803536, 9264.58309376078, 9274.53627318633, 9280.211182049525, 9281.675141370046, 7942.7220047298215, 8176.672933082685, 8199.508029518232, 8413.200745929142, 9077.228435582983, 2331.825113116519, 2427.647991041307, 2430.428849220432, 2443.51129774045, 2447.877606119696, 2453.6442215878687, 2456.238566044523, 2467.494163696438, 2491.862947537885, 2497.2633073243437, 2433.537627850703, 2440.2313271319567, 2464.864432456214, 2470.5800013997664, 2471.88876500557, 2389.713234411873, 2403.735589085199, 2405.4106743847783, 2412.9276121716375, 2414.28778510339, 2402.9279121626378, 2434.087217293515, 2435.1842132885326, 2479.5784716598223, 2479.6776419065586, 9235.215006724437, 9285.860712089325, 9290.628374864997, 9293.349465537414, 9301.627934882597, 5385.542228910547, 5525.613207075467, 5526.917096243559, 5529.612927095105, 5529.778808847641, 3262.168147122469, 3451.2234747181933, 3487.4420092785285, 3508.964910350915, 3540.185757275841, 1287.7810772168755, 1357.321339330335, 1363.5636596289191, 1375.9518416855462, 1409.6546346145158, 11618.883811161864, 11631.59289424866, 11646.825297620542, 11667.241629294154, 11688.824667629993, 4731.243001119804, 4938.333325001079, 4999.400071991312, 5056.0, 5066.847994560169, 10782.245242833083, 10798.67603971882, 10813.080888124598, 10818.24272300947, 10828.620998265023, 5741.0, 5750.1949727038245, 5758.62568933018, 5762.279715035621, 5762.423757624288, 4528.071745292207, 5113.71874546896, 5187.610929180327, 5217.869553261199, 5269.71016594091, 3785.2051069275763, 4171.20747058058, 4190.476190476191, 4292.59220374064, 4309.84915527955, 1308.4896890212824, 1346.2730125731996, 1350.2236214176887, 1356.3625096204769, 1365.5015919189425, 1025.2771795883884, 1025.7845852370936, 1036.7356324137447, 1041.8749750030045, 1042.61944390297, 5906.320773111147, 5942.554308426886, 5971.552133589998, 6073.18012068374, 6100.176476175749, 1043.186314674555, 1060.1095080132725, 1065.6962765611918, 1076.0960792933972, 1090.0462095666292, 3980.1641655252142, 3996.660283875845, 4006.6193711597352, 4006.7395619284653, 4036.979815100906, 10912.50893709785, 11259.718507037389, 11261.042811360961, 11265.774684506265, 11273.71815704614, 11091.500882460308, 11134.777304454028, 11160.665180044612, 11162.107031437465, 11169.162312826407, 8008.439409241297, 8338.332933365316, 8368.581570921551, 8573.571321433903, 8639.524826134497, 6258.468030217483, 6302.117703521602, 6428.4214420702365, 6428.4214420702365, 6523.412892839668, 10049.145822604867, 10137.695869123938, 10242.180625550087, 10272.486375681336, 10349.74125646865, 10313.484325783531, 10376.0, 10470.895291046992, 10486.737831554272, 10509.159267258754, 7702.191269916578, 7897.605119743875, 7961.442699011245, 8024.157463466486, 8143.633536490754, 8767.298616110824, 8799.736007919773, 8816.779580510538, 8829.911700882909, 8852.822943540965, 7462.552246865205, 7632.084149902047, 7635.350995165473, 7813.10149332834, 7882.330001949898, 7605.35354494852, 7638.274363935418, 7657.387409007378, 7698.999130113049, 7736.7292144774665, 6557.606543607399, 6603.537752357481, 6617.07360969455, 6620.172478440195, 6654.101696270942, 5231.712255825966, 5232.947670523246, 5327.227552005015, 5340.038793017139, 5383.7308134593895, 5021.271915572149, 5078.390593128848, 5099.209622508517, 5120.820771272987, 5142.280080788618], "value": 4803.567355923278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.68166490990563, 372.88999745075216, 373.82803910201307, 376.3075940269903, 382.8985318890494, 5371.91942120868, 5408.134193703166, 5411.540019098381, 5417.83746487605, 5452.863678408037, 5076.720780357079, 5213.504717051884, 5235.319408476893, 5266.394364648067, 5293.206019097137, 4241.384999175118, 4247.0, 4258.382534532491, 4263.786810659462, 4304.418903448026, 4081.9183616327805, 4259.74441533509, 4272.829086836523, 4280.957190428087, 4289.6997210195295, 4046.9797651011704, 4113.71204015719, 4226.640735537468, 4251.702380833328, 4270.274053410928, 4227.450431443924, 4239.300515414953, 4239.96481688788, 4258.850940217093, 4262.5950534699305, 3848.5958974307573, 3850.7689538627774, 3858.0740622250737, 3867.4972253607134, 3874.6319099685484, 4997.625178111656, 5198.974005129988, 5202.661826981272, 5230.790768369261, 5288.550473209782, 4720.669553131283, 4729.929051064237, 4742.644301677388, 4747.596454301389, 4750.456101766939, 5587.7485513152005, 5600.971995139998, 5602.971985140089, 5621.690807005613, 5636.577256705764, 3905.9804700976597, 4005.679545636371, 4020.798960051986, 4037.656799172074, 4066.3900414937702, 5750.3962083981, 5754.424557544254, 5784.537237021028, 5796.681182534959, 5801.419858014165, 4127.2570937231585, 4128.60778226074, 4134.503859536875, 4144.668426525871, 4164.8125834337525, 4028.1540876415693, 4119.331670673382, 4182.56083111275, 4184.8744537663915, 4189.2668782963465, 4714.363560919232, 4727.314539391838, 4753.239481682914, 4759.714417134983, 4763.4522029967, 5612.270404847343, 5629.240052592848, 5642.6614403135945, 5672.319321681342, 5692.800751973661, 1315.7236980234068, 1369.513822592985, 1381.2886363522794, 1392.7952321242255, 1400.43982407038, 908.5775114571755, 943.42451104826, 959.7552624080814, 964.2739496301682, 972.5088830140734, 5301.496357845998, 5331.920021199608, 5342.706151161723, 5363.865903352447, 5367.973160134175, 5389.353277606712, 5531.778728850801, 5582.385937546866, 5583.4137415571595, 5596.804111856066, 5253.737313134405, 5285.497877701612, 5295.399128592517, 5311.282976798083, 5319.042572337016, 2893.654450680444, 2896.9855150724534, 2907.819135648264, 2908.75275601572, 2921.7078292171036, 5592.406164243172, 5679.858003549862, 5684.431556844365, 5699.515541178963, 5878.764849406061, 5814.30228372598, 5825.38833422493, 5840.59115861894, 5844.357120716718, 5862.530997520188, 5966.137597857819, 5973.104034394875, 5973.49225315853, 5974.163617093611, 5980.312264089685, 5526.176361800676, 5703.543716502752, 5711.943290491273, 5754.165645981226, 5762.625429347247, 5616.0, 5661.830145095653, 5685.971570142123, 5692.0, 5694.686792226467, 5684.658920464785, 5887.3523912370465, 5890.440408161217, 5919.549710320999, 5938.366949089035, 4086.6934979877224, 4113.732607380511, 4114.033202197541, 4123.814428350792, 4148.958510414857, 5809.8547536311935, 5811.157382179645, 5847.093700476433, 5848.824535263949, 5860.677662728421, 5483.725813709379, 5594.188842617879, 5616.177945424985, 5617.129344951539, 5708.6574805510145, 5602.691851948182, 5603.80386686464, 5612.158176273591, 5616.887662246814, 5617.719114044363, 5320.28176196224, 5420.945790542202, 5506.953678801015, 5602.607817452739, 5605.495505404533, 5526.557875370041, 5557.499825015768, 5620.634658747168, 5630.774769009194, 5633.324001119892, 5493.505584497414, 5498.268045565634, 5524.58565607573, 5540.864122854725, 5559.8610034747835], "value": 4281.691313239173, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.7531740477855, 839.6515446089873, 863.5250612163309, 924.026170805741, 955.2214944819971, 16965.999999999985, 18156.0, 18201.18094685739, 18329.083545822705, 18724.0, 16195.595110122253, 16505.257263423144, 16645.66708665826, 16847.663046739057, 17002.0, 828.1966492502278, 842.6250318608222, 844.3034496540348, 846.7756044648173, 847.0978407995495, 701.4178232067397, 720.2329519062208, 809.9473534220286, 843.9324854011695, 847.1486156412797, 857.3098655582254, 872.3239489395737, 872.4459967920385, 873.0, 873.3929918706501, 863.5423225690395, 864.9827003459927, 865.4677373415367, 865.5801936061006, 865.7272959017952, 535.1517844216921, 550.7769353411875, 621.1459243540132, 640.6028262477247, 641.0, 16402.26189821461, 17444.127793610267, 17454.650906981915, 17455.127243637766, 17456.127193640394, 14609.853901460956, 15026.699466010728, 15313.081215127128, 15331.773458123324, 15826.63744825901, 14669.339879705332, 14785.704285914222, 14882.40266520816, 15059.623509412242, 15171.772423413657, 4757.857264282077, 4769.35613692154, 4819.951800481986, 4830.130576496229, 4992.575631071363, 83543.0, 84480.3930795028, 84765.0, 85181.57409212914, 87493.0, 11742.647720568397, 11813.704657383461, 12030.63908082759, 12037.638870833889, 12246.265224086404, 22817.20139795099, 22897.198598048988, 23214.53570928572, 23228.65157022646, 23643.172488962802, 8465.153484651486, 8540.231379175904, 8722.694705685271, 8736.825263494822, 8771.692990745294, 4713.151632706145, 4832.734199618984, 4957.504249575086, 4977.178765503724, 4981.028699403654, 3935.311320518898, 4024.8993775155855, 4027.5368332641533, 4032.8185231664647, 4039.6768258539246, 671.1543455246425, 673.7709178879106, 680.0377465885815, 685.8388278754488, 697.1146643762394, 687.1239170058216, 696.5402834129552, 697.6372286411038, 715.1775458223085, 716.7957132217397, 11048.0, 11090.5563777448, 11133.1039010125, 11932.821007684892, 12306.692332691755, 855.8673405622138, 858.5321000054985, 872.4067634008808, 880.3045593980811, 884.6549845560237, 4989.201727723546, 4996.175631020914, 5020.121478741279, 5096.796128154906, 5180.326557547494, 72159.2784072163, 75041.24958750451, 75056.0, 76280.61859690666, 78985.60507197543, 59604.403955960755, 59881.101783473285, 67768.6611566939, 69257.0, 69624.30375696179, 59583.0, 61090.69454652698, 61116.694416528524, 61241.100711942934, 61586.0, 9595.0, 9897.703068907656, 9952.402855828674, 10016.198704103508, 10054.64808731691, 43089.78455107704, 43135.568644313156, 43175.0, 43402.0, 43517.78241108901, 52945.7352713234, 53223.73388133034, 53349.19976200283, 54107.45892541182, 54677.179842303194, 12587.962065038482, 12630.179038362845, 12633.431495582905, 12645.810312845499, 12858.228506289652, 14027.789583156056, 14165.575032749033, 14261.0, 14399.640008999859, 14870.256487175815, 12429.142964926417, 12499.375031248219, 12760.553380631633, 13120.868791312347, 13534.93232533831, 14145.858541414455, 14193.645158871112, 14241.786373204617, 14347.210903399995, 14531.346089426213, 13768.24274664903, 13783.51757688476, 14046.1572305662, 14106.153630782186, 14590.0, 11372.886271137182, 11758.764824703287, 12117.81823272634, 12232.816507752568, 12358.134930554776, 10986.801504987041, 11049.72375690582, 11106.666799996014, 11203.495842687262, 11208.55165793359], "value": 7643.599110389489, "unit": "score"}], "name": "dromaeo_css", "value": 7533.184955621399, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 112.0, 111.0, 111.0, 112.0, 111.0, 110.0, 110.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 119.0, 153.0, 123.0, 119.0, 119.0, 116.0, 151.0, 119.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 160.0, 160.0, 161.0, 160.0, 162.0, 160.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 75.0, 75.0, 75.0, 76.0, 91.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 113.0, 83.0, 82.0, 82.0, 93.0, 82.0, 82.0, 105.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 53.0, 49.0, 48.0, 48.0, 48.0, 47.0, 48.0, 48.0, 48.0], "value": 48.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 65.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 79.0, 77.0, 78.0, 78.0, 75.0, 75.0, 75.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 126.0, 131.0, 121.0, 123.0, 120.0, 127.0, 157.0, 123.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 163.0, 158.0, 164.0, 163.0, 160.0, 162.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 62.0, 60.0, 61.0, 61.0, 60.0, 60.0, 61.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1468.5000000000002, "alertThreshold": 2.0}]} 19:26:46 INFO - Return code: 0 19:26:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:26:46 ERROR - # TBPL SUCCESS # 19:26:46 INFO - Running post-action listener: _resource_record_post_action 19:26:46 INFO - [mozharness: 2017-02-07 03:26:46.884815Z] Finished run-tests step (success) 19:26:46 INFO - Running post-run listener: _resource_record_post_run 19:26:46 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 362774528; Read time: 44; Write time: 240204 19:26:46 INFO - TinderboxPrint: CPU usage
13.0% 19:26:46 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 44 19:26:46 INFO - TinderboxPrint: I/O write bytes / time
362,774,528 / 240,204 19:26:46 INFO - TinderboxPrint: CPU idle
8,269.9 (87.0%) 19:26:46 INFO - TinderboxPrint: CPU user
1,190.4 (12.5%) 19:26:46 INFO - TinderboxPrint: Swap in / out
0 / 0 19:26:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109727744; Read time: 0; Write time: 89968 19:26:46 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 252645376; Read time: 44; Write time: 150200 19:26:47 INFO - Running post-run listener: _upload_blobber_files 19:26:47 INFO - Blob upload gear active. 19:26:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:26:47 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 19:26:47 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:26:47 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:26:47 INFO - (blobuploader) - INFO - Open directory for files ... 19:26:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:26:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:26:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:26:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:26:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:26:48 INFO - (blobuploader) - INFO - Done attempting. 19:26:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:26:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:26:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:26:51 INFO - (blobuploader) - INFO - Done attempting. 19:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:26:52 INFO - (blobuploader) - INFO - Done attempting. 19:26:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:26:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:26:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:26:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:26:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:26:53 INFO - (blobuploader) - INFO - Done attempting. 19:26:53 INFO - (blobuploader) - INFO - Iteration through files over. 19:26:53 INFO - Return code: 0 19:26:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:26:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:26:53 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7b5a80ee2b680794e551336ffe8ee2a2b423f8700b98be979ec7ede3236f411bf3d75de2bcb80ad3958e510d45c523b04218a6a8d76d22273b5fed70c9bd78be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98e82142193463cbf455d535681f07e2fc8f1aa07016ca9ddcc8a7b3d3eeaf6464512bf4d8ba1a808ae4907b0f065d9ea01a74ca24ef63154c83d395c7691873", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/863eefae5c3016183e71a5fad5e19d720a3cdc97d9abaf1e56402804835990d8bfb5959afe2555d6eb24bfdce0ba628cdac092b71f714639ab0b10122629725e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7c3ccbe428363fb68559f92450e96bc48142ac904c55c821c73356988d6eaf4a108759ec85868af1ee3a92757fcafd2cd709e97f26b146b951849536c8d70dd"} 19:26:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:26:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:26:53 INFO - Contents: 19:26:53 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7b5a80ee2b680794e551336ffe8ee2a2b423f8700b98be979ec7ede3236f411bf3d75de2bcb80ad3958e510d45c523b04218a6a8d76d22273b5fed70c9bd78be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98e82142193463cbf455d535681f07e2fc8f1aa07016ca9ddcc8a7b3d3eeaf6464512bf4d8ba1a808ae4907b0f065d9ea01a74ca24ef63154c83d395c7691873", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/863eefae5c3016183e71a5fad5e19d720a3cdc97d9abaf1e56402804835990d8bfb5959afe2555d6eb24bfdce0ba628cdac092b71f714639ab0b10122629725e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7c3ccbe428363fb68559f92450e96bc48142ac904c55c821c73356988d6eaf4a108759ec85868af1ee3a92757fcafd2cd709e97f26b146b951849536c8d70dd"} 19:26:53 INFO - Running post-run listener: copy_logs_to_upload_dir 19:26:53 INFO - Copying logs to upload dir... 19:26:53 INFO - mkdir: /builds/slave/test/build/upload/logs 19:26:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.293572 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-02-06 19:26:52.635553) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 19:26:52.639418) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406 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-Non-PGO/sha512/7b5a80ee2b680794e551336ffe8ee2a2b423f8700b98be979ec7ede3236f411bf3d75de2bcb80ad3958e510d45c523b04218a6a8d76d22273b5fed70c9bd78be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98e82142193463cbf455d535681f07e2fc8f1aa07016ca9ddcc8a7b3d3eeaf6464512bf4d8ba1a808ae4907b0f065d9ea01a74ca24ef63154c83d395c7691873", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/863eefae5c3016183e71a5fad5e19d720a3cdc97d9abaf1e56402804835990d8bfb5959afe2555d6eb24bfdce0ba628cdac092b71f714639ab0b10122629725e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7c3ccbe428363fb68559f92450e96bc48142ac904c55c821c73356988d6eaf4a108759ec85868af1ee3a92757fcafd2cd709e97f26b146b951849536c8d70dd"} build_url:https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011485 build_url: 'https://queue.taskcluster.net/v1/task/NW42p2AWTYaC43yHGdSLQg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7b5a80ee2b680794e551336ffe8ee2a2b423f8700b98be979ec7ede3236f411bf3d75de2bcb80ad3958e510d45c523b04218a6a8d76d22273b5fed70c9bd78be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/98e82142193463cbf455d535681f07e2fc8f1aa07016ca9ddcc8a7b3d3eeaf6464512bf4d8ba1a808ae4907b0f065d9ea01a74ca24ef63154c83d395c7691873", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/863eefae5c3016183e71a5fad5e19d720a3cdc97d9abaf1e56402804835990d8bfb5959afe2555d6eb24bfdce0ba628cdac092b71f714639ab0b10122629725e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/d7c3ccbe428363fb68559f92450e96bc48142ac904c55c821c73356988d6eaf4a108759ec85868af1ee3a92757fcafd2cd709e97f26b146b951849536c8d70dd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 19:26:52.663773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:26:52.664157) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1486436768.884720-1299533406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:26:52.748868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 19:26:52.749206) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 19:26:52.749509) ========= ========= Total master_lag: 0.15 =========