builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-084 starttime: 1486639052.89 results: success (0) revision: 7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:32.892304) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:32.892747) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:32.893020) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:32.909264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:32.909583) ========= 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=ea65d483da93c827905c2090000001f5-1486639052.912678-658301881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 03:17:33-- 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% 224M=0s 2017-02-09 03:17:33 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093100 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:33.016063) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:33.016387) ========= 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=ea65d483da93c827905c2090000001f5-1486639052.912678-658301881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020849 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:33.065583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 03:17:33.065932) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b --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 7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b --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=ea65d483da93c827905c2090000001f5-1486639052.912678-658301881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 03:17:33,705 truncating revision to first 12 chars 2017-02-09 03:17:33,705 Setting DEBUG logging. 2017-02-09 03:17:33,705 attempt 1/10 2017-02-09 03:17:33,705 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7bab8cf2a1cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 03:17:34,729 unpacking tar archive at: mozilla-aurora-7bab8cf2a1cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.291536 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 03:17:34.370219) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:34.370534) ========= script_repo_revision: 7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:34.370910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:34.371175) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 03:17:34.379560) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-09 03:17:34.379842) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ea65d483da93c827905c2090000001f5-1486639052.912678-658301881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:17:35 INFO - MultiFileLogger online at 20170209 03:17:35 in /builds/slave/test 03:17:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:17:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:17:35 INFO - {'append_to_log': False, 03:17:35 INFO - 'base_work_dir': '/builds/slave/test', 03:17:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:17:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:17:35 INFO - 'branch': 'Mozilla-Aurora', 03:17:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:17:35 INFO - 'config_files': ('talos/linux_config.py',), 03:17:35 INFO - 'default_actions': ('clobber', 03:17:35 INFO - 'read-buildbot-config', 03:17:35 INFO - 'download-and-extract', 03:17:35 INFO - 'populate-webroot', 03:17:35 INFO - 'create-virtualenv', 03:17:35 INFO - 'install', 03:17:35 INFO - 'run-tests'), 03:17:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:17:35 INFO - 'download_minidump_stackwalk': True, 03:17:35 INFO - 'download_symbols': 'ondemand', 03:17:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:17:35 INFO - 'tooltool.py': '/tools/tooltool.py', 03:17:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:17:35 INFO - '/tools/misc-python/virtualenv.py')}, 03:17:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:17:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:17:35 INFO - 'installer_path': 'installer.exe', 03:17:35 INFO - 'log_level': 'info', 03:17:35 INFO - 'log_name': 'talos', 03:17:35 INFO - 'log_to_console': True, 03:17:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:17:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:17:35 INFO - 'opt_config_files': (), 03:17:35 INFO - 'pip_index': False, 03:17:35 INFO - 'sps_profile': False, 03:17:35 INFO - 'sps_profile_interval': 0, 03:17:35 INFO - 'suite': 'dromaeojs', 03:17:35 INFO - 'system_bits': '32', 03:17:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:17:35 INFO - 'title': 'talos-linux64-ix-084', 03:17:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:17:35 INFO - 'use_talos_json': True, 03:17:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:17:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:17:35 INFO - 'work_dir': 'build'} 03:17:35 INFO - [mozharness: 2017-02-09 11:17:35.114968Z] Running clobber step. 03:17:35 INFO - Running pre-action listener: _resource_record_pre_action 03:17:35 INFO - Running main action method: clobber 03:17:35 INFO - rmtree: /builds/slave/test/build 03:17:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:17:35 INFO - Running post-action listener: _resource_record_post_action 03:17:35 INFO - [mozharness: 2017-02-09 11:17:35.585910Z] Finished clobber step (success) 03:17:35 INFO - [mozharness: 2017-02-09 11:17:35.586011Z] Running read-buildbot-config step. 03:17:35 INFO - Running pre-action listener: _resource_record_pre_action 03:17:35 INFO - Running main action method: read_buildbot_config 03:17:35 INFO - Using buildbot properties: 03:17:35 INFO - { 03:17:35 INFO - "project": "", 03:17:35 INFO - "product": "firefox", 03:17:35 INFO - "who": "cbook@mozilla.com", 03:17:35 INFO - "installer_path": "public/build/target.tar.bz2", 03:17:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:17:35 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 03:17:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:17:35 INFO - "stage_platform": "linux64-pgo", 03:17:35 INFO - "basedir": "/builds/slave/test", 03:17:35 INFO - "buildnumber": 744, 03:17:35 INFO - "platform": "ubuntu64_hw", 03:17:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:17:35 INFO - "slavebuilddir": "test", 03:17:35 INFO - "taskId": "V0bwpQFQT0SOizfti-4m6Q", 03:17:35 INFO - "branch": "mozilla-aurora", 03:17:35 INFO - "script_repo_revision": "production", 03:17:35 INFO - "revision": "7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b", 03:17:35 INFO - "slavename": "talos-linux64-ix-084", 03:17:35 INFO - "repo_path": "releases/mozilla-aurora" 03:17:35 INFO - } 03:17:35 INFO - Finding installer, test and symbols from parent task. 03:17:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V0bwpQFQT0SOizfti-4m6Q'}, attempt #1 03:17:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V0bwpQFQT0SOizfti-4m6Q'}, attempt #1 03:17:36 INFO - Task dependencies: Cp8erld-SjWORy9nYS-vIg 03:17:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg'}, attempt #1 03:17:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2 03:17:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/test_packages.json 03:17:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.crashreporter-symbols.zip 03:17:37 INFO - Running post-action listener: _resource_record_post_action 03:17:37 INFO - [mozharness: 2017-02-09 11:17:37.013005Z] Finished read-buildbot-config step (success) 03:17:37 INFO - [mozharness: 2017-02-09 11:17:37.013225Z] Running download-and-extract step. 03:17:37 INFO - Running pre-action listener: _resource_record_pre_action 03:17:37 INFO - Running main action method: download_and_extract 03:17:37 INFO - mkdir: /builds/slave/test/build/tests 03:17: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')]} 03:17:37 INFO - trying https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.test_packages.json 03:17:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:17:37 INFO - Downloaded 896 bytes. 03:17:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:17:37 INFO - Using the following test package requirements: 03:17:37 INFO - {u'common': [u'target.common.tests.zip'], 03:17:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:17:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:17:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:17:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:17:37 INFO - u'mozbase': [u'target.common.tests.zip'], 03:17:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:17:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:17:37 INFO - u'web-platform': [u'target.common.tests.zip', 03:17:37 INFO - u'target.web-platform.tests.zip'], 03:17:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:17:37 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:17:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.common.tests.zip 03:17:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:17:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.common.tests.zip into memory 03:17:38 INFO - Expected file size: 21855811 03:17:38 INFO - Obtained file size: 21855811 03:17:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:17:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.common.tests.zip 03:17:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:17:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.common.tests.zip into memory 03:17:43 INFO - Expected file size: 21855811 03:17:43 INFO - Obtained file size: 21855811 03:17:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.talos.tests.zip 03:17:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:17:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.talos.tests.zip into memory 03:17:48 INFO - Expected file size: 14403485 03:17:48 INFO - Obtained file size: 14403485 03:17:48 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')]} 03:17:48 INFO - trying https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2 03:17:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:17:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:17:50 INFO - Downloaded 66049496 bytes. 03:17:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2 03:17:50 INFO - mkdir: /builds/slave/test/properties 03:17:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:17:50 INFO - Writing to file /builds/slave/test/properties/build_url 03:17:50 INFO - Contents: 03:17:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2 03:17:50 INFO - Running post-action listener: _resource_record_post_action 03:17:50 INFO - Running post-action listener: set_extra_try_arguments 03:17:50 INFO - [mozharness: 2017-02-09 11:17:50.030102Z] Finished download-and-extract step (success) 03:17:50 INFO - [mozharness: 2017-02-09 11:17:50.030199Z] Running populate-webroot step. 03:17:50 INFO - Running pre-action listener: _resource_record_pre_action 03:17:50 INFO - Running main action method: populate_webroot 03:17:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:17:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:17:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:17:50 INFO - '--tppagecycles', 03:17:50 INFO - '7'], 03:17:50 INFO - 'tests': ['tsvgm']}}, 03:17:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:17:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:17:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:17:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:17:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:17:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:17:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['damp', 'tps']}, 03:17:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'tests': ['damp', 'tps']}, 03:17:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['dromaeo_dom']}, 03:17:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:17:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['basic_compositor_video']}, 03:17:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:17:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['a11yr', 03:17:50 INFO - 'ts_paint', 03:17:50 INFO - 'tpaint', 03:17:50 INFO - 'sessionrestore', 03:17:50 INFO - 'sessionrestore_no_auto_restore', 03:17:50 INFO - 'tabpaint']}, 03:17:50 INFO - 'other-e10s': {'tests': ['a11yr', 03:17:50 INFO - 'ts_paint', 03:17:50 INFO - 'tpaint', 03:17:50 INFO - 'sessionrestore', 03:17:50 INFO - 'sessionrestore_no_auto_restore', 03:17:50 INFO - 'tabpaint']}, 03:17:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:17:50 INFO - 'ts_paint', 03:17:50 INFO - 'tpaint', 03:17:50 INFO - 'sessionrestore', 03:17:50 INFO - 'sessionrestore_no_auto_restore', 03:17:50 INFO - 'tabpaint']}, 03:17:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:17:50 INFO - 'ts_paint', 03:17:50 INFO - 'tpaint', 03:17:50 INFO - 'sessionrestore', 03:17:50 INFO - 'sessionrestore_no_auto_restore', 03:17:50 INFO - 'tabpaint']}, 03:17:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['a11yr', 03:17:50 INFO - 'ts_paint', 03:17:50 INFO - 'tpaint', 03:17:50 INFO - 'sessionrestore', 03:17:50 INFO - 'sessionrestore_no_auto_restore', 03:17:50 INFO - 'tabpaint']}, 03:17:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['a11yr', 03:17:50 INFO - 'ts_paint', 03:17:50 INFO - 'tpaint', 03:17:50 INFO - 'sessionrestore', 03:17:50 INFO - 'sessionrestore_no_auto_restore', 03:17:50 INFO - 'tabpaint']}, 03:17:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['tsvgx', 03:17:50 INFO - 'tsvgr_opacity', 03:17:50 INFO - 'tart', 03:17:50 INFO - 'tscrollx', 03:17:50 INFO - 'cart', 03:17:50 INFO - 'tsvg_static']}, 03:17:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:17:50 INFO - 'tsvgr_opacity', 03:17:50 INFO - 'tart', 03:17:50 INFO - 'tscrollx', 03:17:50 INFO - 'cart', 03:17:50 INFO - 'tsvg_static']}, 03:17:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'talos_options': ['--disable-e10s'], 03:17:50 INFO - 'tests': ['tp5o']}, 03:17:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:17:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'talos_options': ['--disable-e10s', 03:17:50 INFO - '--xperf_path', 03:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:17:50 INFO - 'tests': ['tp5n']}, 03:17:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:17:50 INFO - 'talos_options': ['--xperf_path', 03:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:17:50 INFO - 'tests': ['tp5n']}}, 03:17:50 INFO - 'talos.zip': {'path': '', 03:17:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:17:50 INFO - Running post-action listener: _resource_record_post_action 03:17:50 INFO - [mozharness: 2017-02-09 11:17:50.043379Z] Finished populate-webroot step (success) 03:17:50 INFO - [mozharness: 2017-02-09 11:17:50.043469Z] Running create-virtualenv step. 03:17:50 INFO - Running pre-action listener: _resource_record_pre_action 03:17:50 INFO - Running main action method: create_virtualenv 03:17:50 INFO - Creating virtualenv /builds/slave/test/build/venv 03:17:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:17:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:17:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:17:50 INFO - Using real prefix '/usr' 03:17:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:17:50 INFO - Installing distribute.............................................................................................................................................................................................done. 03:17:52 INFO - Installing pip.................done. 03:17:52 INFO - Return code: 0 03:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:17:52 INFO - Reading from file tmpfile_stdout 03:17:52 INFO - Output received: 03:17:52 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) 03:17:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:52 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 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:17:52 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 03:17:52 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 03:17:52 INFO - Using env: {'DISPLAY': ':0', 03:17:52 INFO - 'HOME': '/home/cltbld', 03:17:52 INFO - 'LANG': 'en_US.UTF-8', 03:17:52 INFO - 'LANGUAGE': 'en_US:en', 03:17:52 INFO - 'LOGNAME': 'cltbld', 03:17:52 INFO - 'MAIL': '/var/mail/cltbld', 03:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:52 INFO - 'MOZ_NO_REMOTE': '1', 03:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:52 INFO - 'NO_EM_RESTART': '1', 03:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:52 INFO - 'PWD': '/builds/slave/test', 03:17:52 INFO - 'SHELL': '/bin/bash', 03:17:52 INFO - 'SHLVL': '1', 03:17:52 INFO - 'TERM': 'linux', 03:17:52 INFO - 'TMOUT': '86400', 03:17:52 INFO - 'USER': 'cltbld', 03:17:52 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:52 INFO - '_': '/tools/buildbot/bin/python'} 03:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:17:53 INFO - Cleaning up... 03:17:53 INFO - Return code: 0 03:17:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:17:53 INFO - Using env: {'DISPLAY': ':0', 03:17:53 INFO - 'HOME': '/home/cltbld', 03:17:53 INFO - 'LANG': 'en_US.UTF-8', 03:17:53 INFO - 'LANGUAGE': 'en_US:en', 03:17:53 INFO - 'LOGNAME': 'cltbld', 03:17:53 INFO - 'MAIL': '/var/mail/cltbld', 03:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:53 INFO - 'MOZ_NO_REMOTE': '1', 03:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:53 INFO - 'NO_EM_RESTART': '1', 03:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:53 INFO - 'PWD': '/builds/slave/test', 03:17:53 INFO - 'SHELL': '/bin/bash', 03:17:53 INFO - 'SHLVL': '1', 03:17:53 INFO - 'TERM': 'linux', 03:17:53 INFO - 'TMOUT': '86400', 03:17:53 INFO - 'USER': 'cltbld', 03:17:53 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:53 INFO - '_': '/tools/buildbot/bin/python'} 03:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:53 INFO - Downloading/unpacking psutil>=3.1.1 03:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:17:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:17:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:17:55 INFO - Installing collected packages: psutil 03:17:55 INFO - Running setup.py install for psutil 03:17:55 INFO - building 'psutil._psutil_linux' extension 03:17:55 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 03:17:55 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 03:17:55 INFO - building 'psutil._psutil_posix' extension 03:17:55 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 03:17:55 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 03:17:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:17:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:17:55 INFO - Successfully installed psutil 03:17:55 INFO - Cleaning up... 03:17:55 INFO - Return code: 0 03:17:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:17:55 INFO - Using env: {'DISPLAY': ':0', 03:17:55 INFO - 'HOME': '/home/cltbld', 03:17:55 INFO - 'LANG': 'en_US.UTF-8', 03:17:56 INFO - 'LANGUAGE': 'en_US:en', 03:17:56 INFO - 'LOGNAME': 'cltbld', 03:17:56 INFO - 'MAIL': '/var/mail/cltbld', 03:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:56 INFO - 'MOZ_NO_REMOTE': '1', 03:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:56 INFO - 'NO_EM_RESTART': '1', 03:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:56 INFO - 'PWD': '/builds/slave/test', 03:17:56 INFO - 'SHELL': '/bin/bash', 03:17:56 INFO - 'SHLVL': '1', 03:17:56 INFO - 'TERM': 'linux', 03:17:56 INFO - 'TMOUT': '86400', 03:17:56 INFO - 'USER': 'cltbld', 03:17:56 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:56 INFO - '_': '/tools/buildbot/bin/python'} 03:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:17:58 INFO - Installing collected packages: mozsystemmonitor 03:17:58 INFO - Running setup.py install for mozsystemmonitor 03:17:58 INFO - Successfully installed mozsystemmonitor 03:17:58 INFO - Cleaning up... 03:17:58 INFO - Return code: 0 03:17:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:17:58 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')]} 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:58 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')]} 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:58 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 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:17:58 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 03:17:58 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 03:17:58 INFO - Using env: {'DISPLAY': ':0', 03:17:58 INFO - 'HOME': '/home/cltbld', 03:17:58 INFO - 'LANG': 'en_US.UTF-8', 03:17:58 INFO - 'LANGUAGE': 'en_US:en', 03:17:58 INFO - 'LOGNAME': 'cltbld', 03:17:58 INFO - 'MAIL': '/var/mail/cltbld', 03:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:58 INFO - 'MOZ_NO_REMOTE': '1', 03:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:58 INFO - 'NO_EM_RESTART': '1', 03:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:58 INFO - 'PWD': '/builds/slave/test', 03:17:58 INFO - 'SHELL': '/bin/bash', 03:17:58 INFO - 'SHLVL': '1', 03:17:58 INFO - 'TERM': 'linux', 03:17:58 INFO - 'TMOUT': '86400', 03:17:58 INFO - 'USER': 'cltbld', 03:17:58 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:58 INFO - '_': '/tools/buildbot/bin/python'} 03:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:58 INFO - Downloading/unpacking jsonschema==2.5.1 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:18:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:18:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:18:00 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 03:18:00 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 03:18:00 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 03:18:00 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 03:18:00 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 03:18:00 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 03:18:00 INFO - Downloading functools32-3.2.3-2.tar.gz 03:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:18:00 INFO - warning: no files found matching '*.txt' 03:18:00 INFO - no previously-included directories found matching 'build' 03:18:00 INFO - no previously-included directories found matching 'dist' 03:18:00 INFO - no previously-included directories found matching '.git*' 03:18:00 INFO - Installing collected packages: jsonschema, functools32 03:18:00 INFO - Running setup.py install for functools32 03:18:01 INFO - warning: no files found matching '*.txt' 03:18:01 INFO - no previously-included directories found matching 'build' 03:18:01 INFO - no previously-included directories found matching 'dist' 03:18:01 INFO - no previously-included directories found matching '.git*' 03:18:01 INFO - Successfully installed jsonschema functools32 03:18:01 INFO - Cleaning up... 03:18:01 INFO - Return code: 0 03:18:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:18:01 INFO - Using env: {'DISPLAY': ':0', 03:18:01 INFO - 'HOME': '/home/cltbld', 03:18:01 INFO - 'LANG': 'en_US.UTF-8', 03:18:01 INFO - 'LANGUAGE': 'en_US:en', 03:18:01 INFO - 'LOGNAME': 'cltbld', 03:18:01 INFO - 'MAIL': '/var/mail/cltbld', 03:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:01 INFO - 'MOZ_NO_REMOTE': '1', 03:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:01 INFO - 'NO_EM_RESTART': '1', 03:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:01 INFO - 'PWD': '/builds/slave/test', 03:18:01 INFO - 'SHELL': '/bin/bash', 03:18:01 INFO - 'SHLVL': '1', 03:18:01 INFO - 'TERM': 'linux', 03:18:01 INFO - 'TMOUT': '86400', 03:18:01 INFO - 'USER': 'cltbld', 03:18:01 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:01 INFO - '_': '/tools/buildbot/bin/python'} 03:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:18:01 INFO - Cleaning up... 03:18:01 INFO - Return code: 0 03:18:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:18:01 INFO - Using env: {'DISPLAY': ':0', 03:18:01 INFO - 'HOME': '/home/cltbld', 03:18:01 INFO - 'LANG': 'en_US.UTF-8', 03:18:01 INFO - 'LANGUAGE': 'en_US:en', 03:18:01 INFO - 'LOGNAME': 'cltbld', 03:18:01 INFO - 'MAIL': '/var/mail/cltbld', 03:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:01 INFO - 'MOZ_NO_REMOTE': '1', 03:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:01 INFO - 'NO_EM_RESTART': '1', 03:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:01 INFO - 'PWD': '/builds/slave/test', 03:18:01 INFO - 'SHELL': '/bin/bash', 03:18:01 INFO - 'SHLVL': '1', 03:18:01 INFO - 'TERM': 'linux', 03:18:01 INFO - 'TMOUT': '86400', 03:18:01 INFO - 'USER': 'cltbld', 03:18:01 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:01 INFO - '_': '/tools/buildbot/bin/python'} 03:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:18:01 INFO - Downloading/unpacking blobuploader==1.2.4 03:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:18:03 INFO - Downloading blobuploader-1.2.4.tar.gz 03:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:18:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:18:03 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 03:18:03 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 03:18:03 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 03:18:03 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 03:18:03 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 03:18:03 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 03:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:18:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:18:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:18:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:18:04 INFO - Downloading docopt-0.6.1.tar.gz 03:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:18:04 INFO - Installing collected packages: blobuploader, requests, docopt 03:18:04 INFO - Running setup.py install for blobuploader 03:18:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:18:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:18:04 INFO - Running setup.py install for requests 03:18:04 INFO - Running setup.py install for docopt 03:18:04 INFO - Successfully installed blobuploader requests docopt 03:18:04 INFO - Cleaning up... 03:18:05 INFO - Return code: 0 03:18:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:18:05 INFO - Using env: {'DISPLAY': ':0', 03:18:05 INFO - 'HOME': '/home/cltbld', 03:18:05 INFO - 'LANG': 'en_US.UTF-8', 03:18:05 INFO - 'LANGUAGE': 'en_US:en', 03:18:05 INFO - 'LOGNAME': 'cltbld', 03:18:05 INFO - 'MAIL': '/var/mail/cltbld', 03:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:05 INFO - 'MOZ_NO_REMOTE': '1', 03:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:05 INFO - 'NO_EM_RESTART': '1', 03:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:05 INFO - 'PWD': '/builds/slave/test', 03:18:05 INFO - 'SHELL': '/bin/bash', 03:18:05 INFO - 'SHLVL': '1', 03:18:05 INFO - 'TERM': 'linux', 03:18:05 INFO - 'TMOUT': '86400', 03:18:05 INFO - 'USER': 'cltbld', 03:18:05 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:05 INFO - '_': '/tools/buildbot/bin/python'} 03:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:18:05 INFO - Running setup.py (path:/tmp/pip-HeYKxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:18:05 INFO - Running setup.py (path:/tmp/pip-oVUL5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:18:05 INFO - Running setup.py (path:/tmp/pip-eCjd8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:18:05 INFO - Running setup.py (path:/tmp/pip-1U1l7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:18:05 INFO - Running setup.py (path:/tmp/pip-v3oZKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:18:05 INFO - Running setup.py (path:/tmp/pip-uzq04w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:18:05 INFO - Running setup.py (path:/tmp/pip-2nu9wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:18:05 INFO - Running setup.py (path:/tmp/pip-pTlwnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:18:05 INFO - Running setup.py (path:/tmp/pip-weWMTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:18:05 INFO - Running setup.py (path:/tmp/pip-pGkkXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:18:05 INFO - Running setup.py (path:/tmp/pip-jYPdnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:18:06 INFO - Running setup.py (path:/tmp/pip-saInV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:18:06 INFO - Running setup.py (path:/tmp/pip-BRpl8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:18:06 INFO - Running setup.py (path:/tmp/pip-I_qE3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:18:06 INFO - Running setup.py (path:/tmp/pip-MqIlON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:18:06 INFO - Running setup.py (path:/tmp/pip-h2jKxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:18:06 INFO - Running setup.py (path:/tmp/pip-EBXeDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:18:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:18:06 INFO - Running setup.py install for manifestparser 03:18:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:18:06 INFO - Running setup.py install for mozcrash 03:18:06 INFO - Running setup.py install for mozdebug 03:18:06 INFO - Running setup.py install for mozdevice 03:18:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:18:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:18:06 INFO - Running setup.py install for mozfile 03:18:06 INFO - Running setup.py install for mozhttpd 03:18:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for mozinfo 03:18:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for mozInstall 03:18:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for mozleak 03:18:07 INFO - Running setup.py install for mozlog 03:18:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for moznetwork 03:18:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for mozprocess 03:18:07 INFO - Running setup.py install for mozprofile 03:18:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for mozrunner 03:18:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:18:07 INFO - Running setup.py install for mozscreenshot 03:18:08 INFO - Running setup.py install for moztest 03:18:08 INFO - Running setup.py install for mozversion 03:18:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:18:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:18:08 INFO - Cleaning up... 03:18:08 INFO - Return code: 0 03:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:18:08 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')]} 03:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:08 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')]} 03:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:08 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 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:18:08 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 03:18:08 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 03:18:08 INFO - Using env: {'DISPLAY': ':0', 03:18:08 INFO - 'HOME': '/home/cltbld', 03:18:08 INFO - 'LANG': 'en_US.UTF-8', 03:18:08 INFO - 'LANGUAGE': 'en_US:en', 03:18:08 INFO - 'LOGNAME': 'cltbld', 03:18:08 INFO - 'MAIL': '/var/mail/cltbld', 03:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:08 INFO - 'MOZ_NO_REMOTE': '1', 03:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:08 INFO - 'NO_EM_RESTART': '1', 03:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:08 INFO - 'PWD': '/builds/slave/test', 03:18:08 INFO - 'SHELL': '/bin/bash', 03:18:08 INFO - 'SHLVL': '1', 03:18:08 INFO - 'TERM': 'linux', 03:18:08 INFO - 'TMOUT': '86400', 03:18:08 INFO - 'USER': 'cltbld', 03:18:08 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:08 INFO - '_': '/tools/buildbot/bin/python'} 03:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:18:08 INFO - Running setup.py (path:/tmp/pip-4P3Egy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:18:08 INFO - Running setup.py (path:/tmp/pip-D9hd2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:18:08 INFO - Running setup.py (path:/tmp/pip-XiIut7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:18:08 INFO - Running setup.py (path:/tmp/pip-R31KkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:18:08 INFO - Running setup.py (path:/tmp/pip-_cb4u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:18:08 INFO - Running setup.py (path:/tmp/pip-brCA6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:18:08 INFO - Running setup.py (path:/tmp/pip-l_v5zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:18:08 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)) 03:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:18:08 INFO - Running setup.py (path:/tmp/pip-ELjLrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:18:09 INFO - Running setup.py (path:/tmp/pip-xrbmdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:18:09 INFO - Running setup.py (path:/tmp/pip-DUrpSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:18:09 INFO - Running setup.py (path:/tmp/pip-bhsnfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:18:09 INFO - Running setup.py (path:/tmp/pip-OIfwC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:18:09 INFO - Running setup.py (path:/tmp/pip-vQrck3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:18:09 INFO - Running setup.py (path:/tmp/pip-2iGS_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:18:09 INFO - Running setup.py (path:/tmp/pip-xvovKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:18:09 INFO - Running setup.py (path:/tmp/pip-oOxnXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:18:09 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)) 03:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:18:09 INFO - Running setup.py (path:/tmp/pip-0xGivz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:18:09 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)) 03:18:09 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)) 03:18:09 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)) 03:18:09 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)) 03:18:09 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)) 03:18:09 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)) 03:18:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:18:09 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 03:18:09 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 03:18:09 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 03:18:09 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 03:18:09 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 03:18:09 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 03:18:11 INFO - Downloading blessings-1.6.tar.gz 03:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:18:11 INFO - Installing collected packages: blessings 03:18:11 INFO - Running setup.py install for blessings 03:18:11 INFO - Successfully installed blessings 03:18:11 INFO - Cleaning up... 03:18:11 INFO - Return code: 0 03:18:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:18:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:18:12 INFO - Reading from file tmpfile_stdout 03:18:12 INFO - Current package versions: 03:18:12 INFO - argparse == 1.2.1 03:18:12 INFO - blessings == 1.6 03:18:12 INFO - blobuploader == 1.2.4 03:18:12 INFO - docopt == 0.6.1 03:18:12 INFO - functools32 == 3.2.3-2 03:18:12 INFO - jsonschema == 2.5.1 03:18:12 INFO - manifestparser == 1.1 03:18:12 INFO - mozInstall == 1.12 03:18:12 INFO - mozcrash == 1.0 03:18:12 INFO - mozdebug == 0.1 03:18:12 INFO - mozdevice == 0.49 03:18:12 INFO - mozfile == 1.2 03:18:12 INFO - mozhttpd == 0.7 03:18:12 INFO - mozinfo == 0.9 03:18:12 INFO - mozleak == 0.1 03:18:12 INFO - mozlog == 3.4 03:18:12 INFO - moznetwork == 0.27 03:18:12 INFO - mozprocess == 0.24 03:18:12 INFO - mozprofile == 0.28 03:18:12 INFO - mozrunner == 6.13 03:18:12 INFO - mozscreenshot == 0.1 03:18:12 INFO - mozsystemmonitor == 0.3 03:18:12 INFO - moztest == 0.8 03:18:12 INFO - mozversion == 1.4 03:18:12 INFO - psutil == 3.1.1 03:18:12 INFO - requests == 1.2.3 03:18:12 INFO - wsgiref == 0.1.2 03:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:18:12 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')]} 03:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:12 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')]} 03:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:12 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 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:18:12 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 03:18:12 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 03:18:12 INFO - Using env: {'DISPLAY': ':0', 03:18:12 INFO - 'HOME': '/home/cltbld', 03:18:12 INFO - 'LANG': 'en_US.UTF-8', 03:18:12 INFO - 'LANGUAGE': 'en_US:en', 03:18:12 INFO - 'LOGNAME': 'cltbld', 03:18:12 INFO - 'MAIL': '/var/mail/cltbld', 03:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:12 INFO - 'MOZ_NO_REMOTE': '1', 03:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:12 INFO - 'NO_EM_RESTART': '1', 03:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:12 INFO - 'PWD': '/builds/slave/test', 03:18:12 INFO - 'SHELL': '/bin/bash', 03:18:12 INFO - 'SHLVL': '1', 03:18:12 INFO - 'TERM': 'linux', 03:18:12 INFO - 'TMOUT': '86400', 03:18:12 INFO - 'USER': 'cltbld', 03:18:12 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:12 INFO - '_': '/tools/buildbot/bin/python'} 03:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 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)) 03:18:12 INFO - Cleaning up... 03:18:12 INFO - Return code: 0 03:18:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:18:12 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')]} 03:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:12 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')]} 03:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:18:12 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 0x18ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2c630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, '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': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', '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 03:18:12 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 03:18:12 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 03:18:12 INFO - Using env: {'DISPLAY': ':0', 03:18:12 INFO - 'HOME': '/home/cltbld', 03:18:12 INFO - 'LANG': 'en_US.UTF-8', 03:18:12 INFO - 'LANGUAGE': 'en_US:en', 03:18:12 INFO - 'LOGNAME': 'cltbld', 03:18:12 INFO - 'MAIL': '/var/mail/cltbld', 03:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:12 INFO - 'MOZ_NO_REMOTE': '1', 03:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:12 INFO - 'NO_EM_RESTART': '1', 03:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:12 INFO - 'PWD': '/builds/slave/test', 03:18:12 INFO - 'SHELL': '/bin/bash', 03:18:12 INFO - 'SHLVL': '1', 03:18:12 INFO - 'TERM': 'linux', 03:18:12 INFO - 'TMOUT': '86400', 03:18:12 INFO - 'USER': 'cltbld', 03:18:12 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:12 INFO - '_': '/tools/buildbot/bin/python'} 03:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:18:12 INFO - Cleaning up... 03:18:12 INFO - Return code: 0 03:18:12 INFO - Running post-action listener: _resource_record_post_action 03:18:12 INFO - Running post-action listener: _start_resource_monitoring 03:18:12 INFO - Starting resource monitoring. 03:18:12 INFO - [mozharness: 2017-02-09 11:18:12.666918Z] Finished create-virtualenv step (success) 03:18:12 INFO - [mozharness: 2017-02-09 11:18:12.667413Z] Running install step. 03:18:12 INFO - Running pre-action listener: _resource_record_pre_action 03:18:12 INFO - Running main action method: install 03:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:18:12 INFO - Reading from file tmpfile_stdout 03:18:12 INFO - Detecting whether we're running mozinstall >=1.0... 03:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:18:12 INFO - Reading from file tmpfile_stdout 03:18:12 INFO - Output received: 03:18:12 INFO - Usage: mozinstall [options] installer 03:18:12 INFO - Options: 03:18:12 INFO - -h, --help show this help message and exit 03:18:12 INFO - -d DEST, --destination=DEST 03:18:12 INFO - Directory to install application into. [default: 03:18:12 INFO - "/builds/slave/test"] 03:18:12 INFO - --app=APP Application being installed. [default: firefox] 03:18:12 INFO - mkdir: /builds/slave/test/build/application 03:18:12 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'] 03:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:18:29 INFO - Reading from file tmpfile_stdout 03:18:29 INFO - Output received: 03:18:29 INFO - /builds/slave/test/build/application/firefox/firefox 03:18:29 INFO - Running post-action listener: _resource_record_post_action 03:18:29 INFO - [mozharness: 2017-02-09 11:18:29.344460Z] Finished install step (success) 03:18:29 INFO - [mozharness: 2017-02-09 11:18:29.344736Z] Running run-tests step. 03:18:29 INFO - Running pre-action listener: _resource_record_pre_action 03:18:29 INFO - Running main action method: run_tests 03:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:18:29 INFO - Python 2.7.3 03:18:29 INFO - Return code: 0 03:18:29 INFO - grabbing minidump binary from tooltool 03:18: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')]} 03:18:29 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 0x1cb5850>, '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 0x1c2a6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ab60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:18:29 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 03:18:29 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 03:18:29 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 03:18:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:18:29 INFO - Return code: 0 03:18:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:18:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:18:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:18:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:18:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 03:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-084 --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 03:18:29 INFO - Using env: {'DISPLAY': ':0', 03:18:29 INFO - 'HOME': '/home/cltbld', 03:18:29 INFO - 'LANG': 'en_US.UTF-8', 03:18:29 INFO - 'LANGUAGE': 'en_US:en', 03:18:29 INFO - 'LOGNAME': 'cltbld', 03:18:29 INFO - 'MAIL': '/var/mail/cltbld', 03:18:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:18:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:29 INFO - 'MOZ_NO_REMOTE': '1', 03:18:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:18:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:18:29 INFO - 'NO_EM_RESTART': '1', 03:18:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:29 INFO - 'PWD': '/builds/slave/test', 03:18:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:18:29 INFO - 'SHELL': '/bin/bash', 03:18:29 INFO - 'SHLVL': '1', 03:18:29 INFO - 'TERM': 'linux', 03:18:29 INFO - 'TMOUT': '86400', 03:18:29 INFO - 'USER': 'cltbld', 03:18:29 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1486639052.912678-658301881', 03:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:29 INFO - '_': '/tools/buildbot/bin/python'} 03:18:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 03:18:29 INFO - mozversion application_buildid: 20170209101614 03:18:29 INFO - mozversion application_changeset: 7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b 03:18:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:18:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:18:29 INFO - mozversion application_name: Firefox 03:18:29 INFO - mozversion application_remotingname: firefox-dev 03:18:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:18:29 INFO - mozversion application_vendor: Mozilla 03:18:29 INFO - mozversion application_version: 53.0a2 03:18:29 INFO - mozversion platform_buildid: 20170209101614 03:18:29 INFO - mozversion platform_changeset: 7bab8cf2a1cf936aeeceb61d8f791ff8f4e9223b 03:18:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:18:29 INFO - mozversion platform_version: 53.0a2 03:18:29 INFO - using testdate: 1486639109 03:18:29 INFO - actual date: 1486639109 03:18:29 INFO - starting webserver on 'localhost:49191' 03:18:29 INFO - SUITE-START | Running 2 tests 03:18:29 INFO - TEST-START | dromaeo_css 03:18:29 INFO - operating with platform_type : linux_ 03:18:29 INFO - Initialising browser for dromaeo_css test... 03:18:29 INFO - TEST-INFO | started process 20606 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRW6fV_/profile http://localhost:49191/getInfo.html) 03:18:33 INFO - PROCESS | 20606 | __metrics Screen width/height:1600/1200 03:18:33 INFO - PROCESS | 20606 | colorDepth:24 03:18:33 INFO - PROCESS | 20606 | Browser inner width/height: 1024/697 03:18:33 INFO - PROCESS | 20606 | __metrics 03:18:34 INFO - PROCESS | 20606 | 1486639114295 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 03:18:34 INFO - PROCESS | 20606 | 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" 03:18:34 INFO - PROCESS | 20606 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:18:35 INFO - TEST-INFO | 20606: exit 0 03:18:35 INFO - Browser initialized. 03:18:35 INFO - Running cycle 1/1 for dromaeo_css test... 03:18:35 INFO - TEST-INFO | started process 20696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRW6fV_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:18:47 INFO - PROCESS | 20696 | RSS: Main: 138072064 03:18:47 INFO - PROCESS | 20696 | 03:21:47 INFO - PROCESS | 20696 | Cycle 1(1): loaded http://localhost:49191/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49191/tests/dromaeo/cssquery-ext.html) 03:21:48 INFO - PROCESS | 20696 | RSS: Main: 237580288 03:21:48 INFO - PROCESS | 20696 | 03:23:37 INFO - PROCESS | 20696 | ************************* 03:23:37 INFO - PROCESS | 20696 | A coding exception was thrown and uncaught in a Task. 03:23:37 INFO - PROCESS | 20696 | 03:23:37 ERROR - PROCESS | 20696 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:23:37 INFO - PROCESS | 20696 | Full stack: 03:23:37 INFO - PROCESS | 20696 | ************************* 03:24:50 INFO - PROCESS | 20696 | Cycle 1(1): loaded http://localhost:49191/tests/dromaeo/cssquery-ext.html (next: http://localhost:49191/tests/dromaeo/cssquery-jquery.html) 03:24:51 INFO - PROCESS | 20696 | RSS: Main: 224612352 03:24:51 INFO - PROCESS | 20696 | 03:27:53 INFO - PROCESS | 20696 | Cycle 1(1): loaded http://localhost:49191/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49191/tests/dromaeo/cssquery-mootools.html) 03:27:53 INFO - PROCESS | 20696 | RSS: Main: 208924672 03:27:53 INFO - PROCESS | 20696 | 03:30:55 INFO - PROCESS | 20696 | Cycle 1(1): loaded http://localhost:49191/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49191/tests/dromaeo/cssquery-prototype.html) 03:30:56 INFO - PROCESS | 20696 | RSS: Main: 170975232 03:30:56 INFO - PROCESS | 20696 | 03:33:59 INFO - PROCESS | 20696 | Cycle 1(1): loaded http://localhost:49191/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49191/tests/dromaeo/cssquery-yui.html) 03:33:59 INFO - PROCESS | 20696 | RSS: Main: 172728320 03:33:59 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | Cycle 1(1): loaded http://localhost:49191/tests/dromaeo/cssquery-yui.html (next: http://localhost:49191/tests/dromaeo/cssquery-dojo.html) 03:37:02 INFO - PROCESS | 20696 | RSS: Main: 263749632 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | __start_tp_report 03:37:02 INFO - PROCESS | 20696 | _x_x_mozilla_page_load 03:37:02 INFO - PROCESS | 20696 | _x_x_mozilla_page_load_details 03:37:02 INFO - PROCESS | 20696 | |i|pagename|runs| 03:37:02 INFO - PROCESS | 20696 | |0;dojo.html;3266.542684024236;3283.589551306087;3298.1589694627864;3312.966870331296;3337.449320862058;5426.484483974026;5484.670919744818;5491.807786727465;5495.697736624484;5512.2834031575885;5512.834614961547;5547.44525547445;5563.054280772269;5565.41563135871;5584.581156413265;4940.752962351886;4963.230699241623;4989.151844186497;4993.850184494471;5062.594992400599;4879.438864530589;4901.2648102784515;4997;5044.2937988681615;5054.166062599685;4951.084049450845;4957.727324997124;4991.625628077891;5036.370453693288;5044.672096313746;5082.364704411949;5083.542481176675;5087.847364579068;5093.032323858473;5113.411957624847;4771.6898401603785;4961.82633607822;4977.477364876709;5018.89962200754;5037.395512538482;5408.59435542332;5473.863153421156;5483.8080667176855;5495.450454954473;5557.249771280865;5915.053591425351;5927.585069045169;5933.554983376221;5940.257467816523;5943.55423343251;5995.400459954013;6005.309389420186;6018.217631707894;6020;6023.849403764941;5444.428335024844;5456.099743542351;5458.099413596791;5472.097103977879;5586.776528938837;5979.611325263844;5980.611260268156;5981.940180598225;5990.400959904062;6020.277566692025;5721.141828725725;5789.218455508537;5792.82621521355;5796.275465566804;5799.82600521985;5595.776168953278;5608.85977850557;5823.941760582425;5843.444872737083;5885.440883116097;5841.4450627190345;5892.6759028253855;5895.056790913433;5907.793227237026;5927.8221653350465;5883.588148829541;5887;5888.911666324922;5892.499137573269;5896.380880007623;4377.299632058855;4435.6229720473475;4440.777961101997;4469.486009108994;4470.150671372428;4029.194161167778;4085.1421201547414;4100.753954762664;4124.546299907008;4128.6284234418445;5699.686517241592;5701.515371193412;5708.971455142698;5711.942880571141;5725.656460612377;5783.074708046693;5845.240118784531;5876.706164691748;5879.176915231871;5994.400559943971;5666.518345940559;5671.064274394678;5676.2337084493065;5688.772449102073;5711.600187986801;5001.199808030698;5026.447090820006;5027.170516864677;5027.4721154279005;5061.544460998528;5959.582829201914;6004.969975150097;6038.607490513103;6104.084387341934;6116.3577824328695;5854.94145058561;5901.616394934315;5934.287885453773;5937.406259374028;5938.851528711817;6182.227221597301;6195.845103872439;6215.160953271431;6220.160278362363;6221.502279817694;5856.472917437451;5918.73365698536;5921.733521991435;5922.822315330547;5926.644401335933;5844.707764611668;5895.970520147372;5898.056310990221;5920.141579470866;5945.3757355477055;5857.970710146422;5862.062070068769;5862.736176872137;5865;5893.823185304448;5848.064309710596;5858.619189752652;5858.736356863866;5860.38465961085;5860.912086318624;5569.526590239875;5610.18652295423;5674.205611214351;5718.085106382959;5743.684097374685;5787.045137552257;5831.416858314305;5836.474717275296;5838.095095260242;5858;5766.3368712598585;5773.740181691918;5781.306243250837;5781.971090144522;5785.161151632906 03:37:02 INFO - PROCESS | 20696 | |1;ext.html;542.2059116992848;589.8171566814287;618.4959258204563;642.9324920883304;666.9499787515938;35645;36272.27455450893;40280.395794063115;40307.99999999996;46804.76597617016;44497.33254001185;50597;50718.49281507184;50746.49253507473;50782;9373.390729602554;9559.28305377096;9561.426314421122;9561.521923903812;9566.521673916312;8514.574271286441;8712.520811355373;9361.48511831849;9478.952605236998;11044.668659940215;11441;11449.942750286236;11453.31280123191;11454.885451145465;11462.254953428042;11339.886601133965;11395.430228488542;11408.71478213043;11418.657440276764;11424.200305978586;11186.496607652594;11252.718682032933;11253.212275140744;11254.099672026217;11257.831132533018;46237;46792.76603616994;46915;46985.295220571716;47013.764931175465;4377.671674624384;4433.179861725591;4461.620230780315;4550;4592.15045216636;35131;35285.823570881985;35551;35873;36195.63804361975;4416.867493975186;4435.733855968652;4457.665675074382;4560.566746159109;4679.391679081732;254084;255367;256332;257406;257454;60320.39679603148;60390.69804650949;60439;60453;60489.395106048374;69883;70322.29677703259;70347.64826175837;70501.64749176221;70640;19139;19142.23431062742;19142.521436963907;19147.8085219146;19288.8071119287;14990.325435355435;15095.32071056805;15097.24513774307;15100.169490677898;15105.54683359479;17096.914515427343;17545.82454175442;18613.906930465262;18939.715904261448;19079.141438635295;6763.5265531413315;6926.2034865990045;7030.402415794612;7147.392471639865;7158.642067896584;956.784723437221;962.1965658675085;967.7725734452398;968.375397868371;978.3445091788519;31619.367612647617;32866.17834554155;33812;33957.830210848784;33970;1064.5528877871313;1140.8516892804048;1158.8956993870424;1165.597868735285;1173.4484792147678;16944.322227111024;18112.909435452737;18186.18162182705;18198.727019094724;18300.359487418144;478862;482559;483664;486067;486472;70286;70534.29465705277;70551.64724176346;70584.2941570588;70625.29374706188;1475.0633347823994;1483.5994281544138;1514.5834895403852;1517.8102737157856;1521.7260893039138;32565.34869302648;33875.322493549495;34112;34209.657903420644;34306;58072.41927580839;58669;58674.119888200854;58743.11885321809;58803;383511;386094;386724;386825;387407;44826.327605086604;45287;45495.545044550454;45545.77227113976;45794;44916.77541612271;45900.77049614864;48864.26703599378;51372.74313628408;51538;42891.14217715691;43212.567874320855;43846;45482.54517454783;45743.313850292936;42652.360214596185;45165;45403.09193816171;45736.771316143204;46075.53924460713;45017.32474012827;45869.08261834811;45903.31145032894;46094.53905460903;46260;37463.25073498569;38978;39343.606563933994;39868.60131398649;39914.60085399109;29210.958086809696;29491.85254073716;30021;30147;30227 03:37:02 INFO - PROCESS | 20696 | |2;jquery.html;439.8130794412374;446.5958307731502;448.18877831125667;451.19236566545885;464.7420681521756;20278.391648250537;21356.786432135694;21801.01895414706;21884.12463501462;22581.661275080885;14770;16026.679466410666;22750.203742869002;23237.535249295004;23399.766002339973;5795.3335366432975;5862.237909071815;6011.128386383972;6022.156898034257;6026;5744.052231381817;5903.911441328362;5906.497947674453;5906.527477801766;5906.852328691795;5909.7636094556165;5911.5270778337845;5915.438033386821;5919.348871624118;6189.288231853327;6494.155759751226;6593.340665933392;6593.835154121161;6595.70319335631;6596.340365963413;6287.151234583318;6412.583182093149;6440.388163124542;6453.064305675651;6458.418742313214;15940.043597384194;16148.83851161497;20417.4895627609;26411.603825942435;26753.06364277241;2944.6024786653743;2949.9705002950127;2953.571732098855;2960.8371539565314;2995.3859458810894;11316.773664526663;11323.660290191225;11487.885121148765;11695.649130526013;11814.940925295405;1985.3746069987908;2023.5952809438024;2028.107632641633;2071.4510654676524;2095.8323334133233;80122.19877801205;89628.55185724095;97278.51360743222;97764;98724;12029;12140.817887731691;12317.199382040139;12764.595894451602;12998.740025199442;17691.55771105707;17732;17755.11224438773;17768.111594420487;17772.111394430227;9453.858192127123;9485.762855928657;9637.662681806103;9746.171575416169;9867.802643947081;9258.87045979415;9287.396319239228;9327.766805829773;9346.392484488486;9548.6154507596;5223.242629818655;5360.839174824761;5455.427180146107;5521.861953451115;5548.639338442988;2124.840636952235;2165.3071017274638;2210.270610698498;2226.1651880544796;2247.6291411917177;503.8261799679105;509.69673044539184;894.190757364586;923.3767207135157;932.5616960028775;12717.364131793373;12985.155964862253;13419.261940593362;13427.731445371039;13618.046736728533;533.703794394115;534.1160379571814;546.9808556700497;555.361334465368;556.6520924422236;7022.719091236396;7085.716571337089;7208.207097219301;7230.74692385764;7372.889406658905;656117;664972.0508384659;669142;672778;673003;99193.50403247938;113388.43305783418;115331;115986;117522.41238793751;526.0478533853737;540.7269328988914;540.8512659018801;543.6466296907042;544.3087279155451;12126.211796233216;12210.023198144305;12234.938825305817;12268.44791984345;12480.563180288646;41291.7935410321;42145.78927105345;44421.77789111034;44869;45063.54936450725;61927.38072619216;61975;62577.374226257154;64027.67986160226;64829.67585162233;9726.805463890541;9754.31719779637;9842.704718858446;9910.405375677483;9927.255455840985;11760.76478470443;14362.066465679698;14490.85509144895;14666.26668666541;14725.116493010453;11864.584739534075;12222.144449888778;12321.137520373488;12417.875821241672;12597.11820172579;6162.7534898605345;12361.258324500559;12512.124151309321;12532.87467125317;12560.681128481378;12360.38198090074;12619.36903154857;12623.179493332904;12779.872201277869;12847.29339886278;9679.274054445805;9995.500224988868;10025.598976040877;10174.338667986558;10202.234832387749;12521.499140034297;12547.309897955334;12547.560835370718;12639.620811375673;12814.103012789015 03:37:02 INFO - PROCESS | 20696 | |3;mootools.html;1338.1636477201748;1465.6555709408287;1491.7389456845049;1506.058713304185;1508.5398953319236;7788.805279868006;8409.285210757085;8688.218060374573;8705.477671339724;8905.020447750741;8660.8700869487;8709.869351959725;8746.431481953668;8747.518886461243;8752.037275899645;2109.5991761565256;2169.3275084723787;2384.3681424422584;2421.33413311339;2426.33275849142;2331.25399872041;2385.1771138957033;2396.988015059931;2400.7479214682467;2401.687780588521;2332.3935776698095;2349.4126468382906;2354.470244195051;2359.9410014749596;2362.232274510782;2394.353524548362;2397.6643269942224;2420.3828023853803;2422.321749910028;2424;2349.447879748258;2361.5985282502015;2377.6314671225987;2380.345405013618;2381.57131716291;8655;8676.219140277406;8684.392092553524;8732.257758090569;8736.956315218447;5281.287026251445;5429.67421954684;5430.782768689248;5435.42927992559;5436.972815135899;3135.5766971459016;3146;3290.52287418323;3306.718928891047;3311.4536101543217;1381.378379729126;1409.1193004372267;1422.1467119728184;1472.3448065610808;1517.0442621148716;12200.816987745191;12218.511259549607;12226.694332641577;12236.082293827909;12252.693682657915;4588.931166032512;4604.40142781443;4685.0395668887795;4763.095011947788;4799.592034677092;10411.687649370531;10480.213983951235;10508.527116279785;10554.525046372932;10565.418901960466;5439.265699130646;5448.91826622601;5449.100898351728;5450.863728406822;5451.100568406168;4925.064237794879;4954.083494553519;5027.597792176617;5053.9747301264;5076.416212135578;3851.171998020383;3957.920841583152;3969.0672691917375;3999.1201935574127;4010.3984402339884;1223.2049168040667;1223.3393967257582;1231.408923716621;1234.3643023842728;1241.3606992398923;929.6374413978554;933.1601558597316;935.3826474526779;951.1534734086669;952.7332346942867;5700.515456186271;5846.269216347957;5885.705714714247;5905.055191169392;5987.850303742442;638.0716058135401;640.8173670503884;644.9419552240226;646.7736292297752;659.7097277198113;3727.142757165867;3786.7159963002887;3796.8101594920145;3824.338687407527;3828.3300422425964;10405.53175107122;10745.032947034804;10766.192535559865;10772.946135269274;10814.188935829845;10697.465126743631;10734.194935379877;10799.730006749736;10855.077318427864;10867.565297388173;7900.170482099412;8181.222783835526;8253.33973282136;8261.38039647029;8316.584170791557;5646.576506761927;5758.971205143947;5826.41735826414;5827.125931110368;5847.5906686533235;9345.579448924951;9533.570989305637;9614.086661767122;9724.95137524308;9743.269254805777;8408.537530435884;9732.318737688294;9926.950365248127;9943.105120539187;9948.204143668345;7170.139583250043;7579.583122928292;7745.419093567893;7759.922400776146;7943.880841787262;8516.489010659381;8516.914830851612;8541.487510749375;8653.524056176971;8692.130786921258;7665.6550455228535;7815.101263354787;7854.41091918097;8003.11965683786;8125.106238313899;7720.073591169095;7846.60766961661;7909;8021.5187088774865;8058;6665.700043498151;6713.697883595348;6750.729970801113;6800.727970881109;6812.965935170292;4906.6320025997475;5030;5044.722540260321;5061.038402703474;5123.28274041627;5189.247559103985;5202.089634313904;5237.292965449616;5291.629585929102;5402.405735369028 03:37:02 INFO - PROCESS | 20696 | |4;prototype.html;387.6879112314587;391.18242872396695;391.4871518311013;392.6230818414322;397.53488418550296;5173.767180476878;5205.505476979685;5271.393789714174;5302.4697530247;5336.626436149471;5184.533391994729;5284.969430960964;5290.788368465257;5310.583684423587;5334.973325133369;4121.938170927424;4195.979020104911;4211.642010429117;4238.503234831262;4259.616634502894;4288.76411797351;4291.914161716763;4298.828046878121;4301.2902871026245;4318.438602981608;4276.5295817460055;4281.785910704468;4284.871453856389;4290.227759003394;4314.899700576349;4117.332480345465;4177.3316269396755;4295.828166873337;4300.397944287803;4304.203722311367;3812.2566099610585;3812.7140464464997;3813.3517302058717;3814.855543336988;3840.7531594017532;5162.786745114895;5166.405863325728;5191.766370513298;5207.557357624606;5222.4255331913455;4552.860155726803;4908.631852611033;4918.213085906238;4923.926141107886;4956.207006878882;5527.585431092684;5556.388797232301;5564.527015203713;5567.359753628344;5580.162975553659;4019.577944315834;4024.5371782245124;4055.71609987301;4059.573744756817;4059.6752259819145;5629.748100759688;5774.653520788766;5785.82642520725;5797.507211886995;5808.2449281593545;4105.4662893823615;4121.711480196357;4129.132882094734;4144.233316836395;4147.751134931914;4179.951823606471;4194.958050419517;4207.747535147901;4208.873733787991;4216.198922204771;4696.264947198209;4787.832425865078;4789.736564488987;4818.566329030404;4823.903521929542;5630.5214056805635;5637;5642.927843709764;5651.265335506358;5681.545476361881;1403.9649008774659;1404.2346920928119;1406.606150277924;1413.6570258254699;1416.7874818777059;921.2721949659693;956.5456408206186;965.9372140810825;971.4025874087386;988.3723835364596;5218.347706536683;5222.399424066281;5240.764165612557;5288.391834938954;5291.031726205346;5519.261432648703;5526.723663816793;5533.533613592401;5541.556675465953;5552.028395030853;5117.744112794346;5220.399654039758;5295.708736019479;5306.867328316745;5322.627416080914;2870.6842247352774;2902.492063888811;2906.6657334406814;2926.536731634183;2937.221636266391;5611.438856114356;5631.1271752878365;5653.660780353192;5668;5678.943210567924;5350.30446042012;5597.80407685729;5608.719564021864;5625.54995600351;5629.311206637993;5662.348829884616;5671.688057156814;5672.914906276409;5682.431756824284;5704.229928959538;5351.331083614548;5524.1989911463415;5527.585431092603;5542.584306176972;5592.245046918614;5411.648242864201;5433.4566543345345;5469.374700143873;5471.343438787371;5478.233047373451;5588.692621905834;5699.544036477155;5701.572382071444;5703.887741890291;5713.971649986666;4063.9390409143293;4084.182538770219;4085.203385339949;4098.221337945781;4098.4876890388705;5682.573806964411;5688.175214593778;5688.345840228427;5688.943110568841;5710.600257981901;5445.50990410865;5529.861753456034;5532.557395408277;5546.639468434539;5563.748156664718;5555.22226888244;5559.916601250903;5561.4716601922755;5568.248286481273;5574.888502230014;5297.258383826191;5528.009916430163;5530.5022547969265;5537.473939975695;5542.3903370628395;5581.553475721851;5596.244506991667;5650.576206784425;5654.773809047592;5660.433956604471;5527.226188333556;5535.584831137761;5541.141123125922;5565.38780734127;5570.860728481658 03:37:02 INFO - PROCESS | 20696 | |5;yui.html;818.5866137600511;844.8479273730726;847.6990668312752;910.968116115936;922.9538523073846;17976.550586235335;18549.907250463762;18724.344647937323;18814.905925470386;19617.215311387565;17559.29762809486;17683.911580442113;18062.5484362891;18075.186616602252;18113.1849066792;802.4543310548839;819.8114433680228;822.0382152881143;822.8518866604009;823.7899335669396;685.5817951049855;706.780897921646;790.731151408521;807.3662175192475;810.7040930060544;866.8959724833029;867.119873328572;868.674247157316;869.1525762381691;869.313242538397;863.6415887406719;864.0927026622022;864.191980498233;864.2869632553137;865.0484467086218;528.6695815115553;540.1169088540225;603.1164344235672;611.8348046027547;617.4350469320566;16502.917485412618;17291.221895014627;17407.91296043512;17453.21460534279;17454;14798.48205312809;15336.233188340539;15498.922505387514;15662.138582377964;15727.22546588822;13718;13763;13826.308684565833;13880.722385552232;13968.650783730387;4402.361657559636;4406.647468202536;4410.955890441087;4455.331700244957;4463.531329210418;82296.17703822945;83111.584442078;83442.16557834465;83926;83950.58024709838;11841.703957401134;11941.294860809396;12008.279503229835;12082.939585302192;12288.877111228774;22016.229431969805;22100.557988840133;22301;22531.0987560499;22723.431914202276;8690.695825646071;8738.126187381211;8837.381383303236;8911.955440222757;9060.728178154666;4737.644676649196;4742.430908291027;4743.430788305425;4821.927671084936;4851.830185943475;3660.377735784923;3717.1450566369886;3754.530683664542;3755;3760.0787806986914;600.8527910661916;604.8366940926013;609.6403122157948;613.2242712968099;622.1228068423592;762.7940456076939;764.8489024018816;784.4312873166908;786.92130786922;786.9252421019994;10202.642907498202;10402.843957340647;10548.525316360781;10591.258611897247;10715.587534765727;746.6304179431216;750.7785203365021;764.4839733180081;782.5734974438944;782.8551717932087;4806.975965120222;4876.390451193601;4903.387076615423;4930.630202734809;4940.382452193476;63872.68063659652;78213.60893195498;85327.5733621328;87232.56383718041;87756;73951.26048739551;74451.41144017271;75212;76727.23272767312;78377.21622783813;61054;62262;62324;63378.36621633724;63715.36284637095;8999.460032398078;9077.727668169966;9250.167484926389;9314.25485961135;9317.906820931705;46975.55208964543;47239.29141062818;48451;48592;48756.756216218695;56471.43528564662;56695.43304567067;57392;57755;59181.40818591759;11860.525578976743;12493.500259989862;12525.874741252472;12622.87377126217;13264.80102798478;14865.215914313898;15088.54734357971;15460;15572.299246533707;16063.75904361459;14476;15136.772948406004;15238.695226095639;15321.46374876874;15413.229338532805;14734.410623574937;15372;15439.305231264845;15574.766378504104;15723.213839308217;14955.953083284066;14967.251637418303;14975.857397363026;15175.317110730264;15323.310451029953;11115.777684446428;11171.99452049287;11179.441027948407;11452.3701196435;11473;12292.569760058355;12408.875911240772;12506.937465312616;12793.744125117631;12877.16298440636 03:37:02 INFO - PROCESS | 20696 | __end_tp_report 03:37:02 INFO - PROCESS | 20696 | __start_cc_report 03:37:02 INFO - PROCESS | 20696 | _x_x_mozilla_cycle_collect,931 03:37:02 INFO - PROCESS | 20696 | __end_cc_report 03:37:02 INFO - PROCESS | 20696 | __startTimestamp1486640222324__endTimestamp 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | ------- Summary: start ------- 03:37:02 INFO - PROCESS | 20696 | Number of tests: 6 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | [#0] dojo.html Cycles:165 Average:5495.79 Median:5743.68 stddev:620.16 (10.8%) stddev-sans-first:596.89 03:37:02 INFO - PROCESS | 20696 | Values: 3266.5 3283.6 3298.2 3313.0 3337.4 5426.5 5484.7 5491.8 5495.7 5512.3 5512.8 5547.4 5563.1 5565.4 5584.6 4940.8 4963.2 4989.2 4993.9 5062.6 4879.4 4901.3 4997.0 5044.3 5054.2 4951.1 4957.7 4991.6 5036.4 5044.7 5082.4 5083.5 5087.8 5093.0 5113.4 4771.7 4961.8 4977.5 5018.9 5037.4 5408.6 5473.9 5483.8 5495.5 5557.2 5915.1 5927.6 5933.6 5940.3 5943.6 5995.4 6005.3 6018.2 6020.0 6023.8 5444.4 5456.1 5458.1 5472.1 5586.8 5979.6 5980.6 5981.9 5990.4 6020.3 5721.1 5789.2 5792.8 5796.3 5799.8 5595.8 5608.9 5823.9 5843.4 5885.4 5841.4 5892.7 5895.1 5907.8 5927.8 5883.6 5887.0 5888.9 5892.5 5896.4 4377.3 4435.6 4440.8 4469.5 4470.2 4029.2 4085.1 4100.8 4124.5 4128.6 5699.7 5701.5 5709.0 5711.9 5725.7 5783.1 5845.2 5876.7 5879.2 5994.4 5666.5 5671.1 5676.2 5688.8 5711.6 5001.2 5026.4 5027.2 5027.5 5061.5 5959.6 6005.0 6038.6 6104.1 6116.4 5854.9 5901.6 5934.3 5937.4 5938.9 6182.2 6195.8 6215.2 6220.2 6221.5 5856.5 5918.7 5921.7 5922.8 5926.6 5844.7 5896.0 5898.1 5920.1 5945.4 5858.0 5862.1 5862.7 5865.0 5893.8 5848.1 5858.6 5858.7 5860.4 5860.9 5569.5 5610.2 5674.2 5718.1 5743.7 5787.0 5831.4 5836.5 5838.1 5858.0 5766.3 5773.7 5781.3 5782.0 5785.2 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | [#1] ext.html Cycles:180 Average:57389.99 Median:4425.02 stddev:101872.36 (2302.2%) stddev-sans-first:102068.72 03:37:02 INFO - PROCESS | 20696 | Values: 542.2 589.8 618.5 642.9 666.9 35645.0 36272.3 40280.4 40308.0 46804.8 44497.3 50597.0 50718.5 50746.5 50782.0 9373.4 9559.3 9561.4 9561.5 9566.5 8514.6 8712.5 9361.5 9479.0 11044.7 11441.0 11449.9 11453.3 11454.9 11462.3 11339.9 11395.4 11408.7 11418.7 11424.2 11186.5 11252.7 11253.2 11254.1 11257.8 46237.0 46792.8 46915.0 46985.3 47013.8 4377.7 4433.2 4461.6 4550.0 4592.2 35131.0 35285.8 35551.0 35873.0 36195.6 4416.9 4435.7 4457.7 4560.6 4679.4 254084.0 255367.0 256332.0 257406.0 257454.0 60320.4 60390.7 60439.0 60453.0 60489.4 69883.0 70322.3 70347.6 70501.6 70640.0 19139.0 19142.2 19142.5 19147.8 19288.8 14990.3 15095.3 15097.2 15100.2 15105.5 17096.9 17545.8 18613.9 18939.7 19079.1 6763.5 6926.2 7030.4 7147.4 7158.6 956.8 962.2 967.8 968.4 978.3 31619.4 32866.2 33812.0 33957.8 33970.0 1064.6 1140.9 1158.9 1165.6 1173.4 16944.3 18112.9 18186.2 18198.7 18300.4 478862.0 482559.0 483664.0 486067.0 486472.0 70286.0 70534.3 70551.6 70584.3 70625.3 1475.1 1483.6 1514.6 1517.8 1521.7 32565.3 33875.3 34112.0 34209.7 34306.0 58072.4 58669.0 58674.1 58743.1 58803.0 383511.0 386094.0 386724.0 386825.0 387407.0 44826.3 45287.0 45495.5 45545.8 45794.0 44916.8 45900.8 48864.3 51372.7 51538.0 42891.1 43212.6 43846.0 45482.5 45743.3 42652.4 45165.0 45403.1 45736.8 46075.5 45017.3 45869.1 45903.3 46094.5 46260.0 37463.3 38978.0 39343.6 39868.6 39914.6 29211.0 29491.9 30021.0 30147.0 30227.0 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | [#2] jquery.html Cycles:180 Average:35090.52 Median:447.39 stddev:109823.62 (24547.5%) stddev-sans-first:110100.89 03:37:02 INFO - PROCESS | 20696 | Values: 439.8 446.6 448.2 451.2 464.7 20278.4 21356.8 21801.0 21884.1 22581.7 14770.0 16026.7 22750.2 23237.5 23399.8 5795.3 5862.2 6011.1 6022.2 6026.0 5744.1 5903.9 5906.5 5906.5 5906.9 5909.8 5911.5 5915.4 5919.3 6189.3 6494.2 6593.3 6593.8 6595.7 6596.3 6287.2 6412.6 6440.4 6453.1 6458.4 15940.0 16148.8 20417.5 26411.6 26753.1 2944.6 2950.0 2953.6 2960.8 2995.4 11316.8 11323.7 11487.9 11695.6 11814.9 1985.4 2023.6 2028.1 2071.5 2095.8 80122.2 89628.6 97278.5 97764.0 98724.0 12029.0 12140.8 12317.2 12764.6 12998.7 17691.6 17732.0 17755.1 17768.1 17772.1 9453.9 9485.8 9637.7 9746.2 9867.8 9258.9 9287.4 9327.8 9346.4 9548.6 5223.2 5360.8 5455.4 5521.9 5548.6 2124.8 2165.3 2210.3 2226.2 2247.6 503.8 509.7 894.2 923.4 932.6 12717.4 12985.2 13419.3 13427.7 13618.0 533.7 534.1 547.0 555.4 556.7 7022.7 7085.7 7208.2 7230.7 7372.9 656117.0 664972.1 669142.0 672778.0 673003.0 99193.5 113388.4 115331.0 115986.0 117522.4 526.0 540.7 540.9 543.6 544.3 12126.2 12210.0 12234.9 12268.4 12480.6 41291.8 42145.8 44421.8 44869.0 45063.5 61927.4 61975.0 62577.4 64027.7 64829.7 9726.8 9754.3 9842.7 9910.4 9927.3 11760.8 14362.1 14490.9 14666.3 14725.1 11864.6 12222.1 12321.1 12417.9 12597.1 6162.8 12361.3 12512.1 12532.9 12560.7 12360.4 12619.4 12623.2 12779.9 12847.3 9679.3 9995.5 10025.6 10174.3 10202.2 12521.5 12547.3 12547.6 12639.6 12814.1 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | [#3] mootools.html Cycles:180 Average:5758.40 Median:5195.67 stddev:3247.45 (62.5%) stddev-sans-first:3239.57 03:37:02 INFO - PROCESS | 20696 | Values: 1338.2 1465.7 1491.7 1506.1 1508.5 7788.8 8409.3 8688.2 8705.5 8905.0 8660.9 8709.9 8746.4 8747.5 8752.0 2109.6 2169.3 2384.4 2421.3 2426.3 2331.3 2385.2 2397.0 2400.7 2401.7 2332.4 2349.4 2354.5 2359.9 2362.2 2394.4 2397.7 2420.4 2422.3 2424.0 2349.4 2361.6 2377.6 2380.3 2381.6 8655.0 8676.2 8684.4 8732.3 8737.0 5281.3 5429.7 5430.8 5435.4 5437.0 3135.6 3146.0 3290.5 3306.7 3311.5 1381.4 1409.1 1422.1 1472.3 1517.0 12200.8 12218.5 12226.7 12236.1 12252.7 4588.9 4604.4 4685.0 4763.1 4799.6 10411.7 10480.2 10508.5 10554.5 10565.4 5439.3 5448.9 5449.1 5450.9 5451.1 4925.1 4954.1 5027.6 5054.0 5076.4 3851.2 3957.9 3969.1 3999.1 4010.4 1223.2 1223.3 1231.4 1234.4 1241.4 929.6 933.2 935.4 951.2 952.7 5700.5 5846.3 5885.7 5905.1 5987.9 638.1 640.8 644.9 646.8 659.7 3727.1 3786.7 3796.8 3824.3 3828.3 10405.5 10745.0 10766.2 10772.9 10814.2 10697.5 10734.2 10799.7 10855.1 10867.6 7900.2 8181.2 8253.3 8261.4 8316.6 5646.6 5759.0 5826.4 5827.1 5847.6 9345.6 9533.6 9614.1 9725.0 9743.3 8408.5 9732.3 9927.0 9943.1 9948.2 7170.1 7579.6 7745.4 7759.9 7943.9 8516.5 8516.9 8541.5 8653.5 8692.1 7665.7 7815.1 7854.4 8003.1 8125.1 7720.1 7846.6 7909.0 8021.5 8058.0 6665.7 6713.7 6750.7 6800.7 6813.0 4906.6 5030.0 5044.7 5061.0 5123.3 5189.2 5202.1 5237.3 5291.6 5402.4 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | [#4] prototype.html Cycles:180 Average:4675.84 Median:5304.67 stddev:1336.73 (25.2%) stddev-sans-first:1301.16 03:37:02 INFO - PROCESS | 20696 | Values: 387.7 391.2 391.5 392.6 397.5 5173.8 5205.5 5271.4 5302.5 5336.6 5184.5 5285.0 5290.8 5310.6 5335.0 4121.9 4196.0 4211.6 4238.5 4259.6 4288.8 4291.9 4298.8 4301.3 4318.4 4276.5 4281.8 4284.9 4290.2 4314.9 4117.3 4177.3 4295.8 4300.4 4304.2 3812.3 3812.7 3813.4 3814.9 3840.8 5162.8 5166.4 5191.8 5207.6 5222.4 4552.9 4908.6 4918.2 4923.9 4956.2 5527.6 5556.4 5564.5 5567.4 5580.2 4019.6 4024.5 4055.7 4059.6 4059.7 5629.7 5774.7 5785.8 5797.5 5808.2 4105.5 4121.7 4129.1 4144.2 4147.8 4180.0 4195.0 4207.7 4208.9 4216.2 4696.3 4787.8 4789.7 4818.6 4823.9 5630.5 5637.0 5642.9 5651.3 5681.5 1404.0 1404.2 1406.6 1413.7 1416.8 921.3 956.5 965.9 971.4 988.4 5218.3 5222.4 5240.8 5288.4 5291.0 5519.3 5526.7 5533.5 5541.6 5552.0 5117.7 5220.4 5295.7 5306.9 5322.6 2870.7 2902.5 2906.7 2926.5 2937.2 5611.4 5631.1 5653.7 5668.0 5678.9 5350.3 5597.8 5608.7 5625.5 5629.3 5662.3 5671.7 5672.9 5682.4 5704.2 5351.3 5524.2 5527.6 5542.6 5592.2 5411.6 5433.5 5469.4 5471.3 5478.2 5588.7 5699.5 5701.6 5703.9 5714.0 4063.9 4084.2 4085.2 4098.2 4098.5 5682.6 5688.2 5688.3 5688.9 5710.6 5445.5 5529.9 5532.6 5546.6 5563.7 5555.2 5559.9 5561.5 5568.2 5574.9 5297.3 5528.0 5530.5 5537.5 5542.4 5581.6 5596.2 5650.6 5654.8 5660.4 5527.2 5535.6 5541.1 5565.4 5570.9 03:37:02 INFO - PROCESS | 20696 | 03:37:02 INFO - PROCESS | 20696 | [#5] yui.html Cycles:180 Average:18770.42 Median:4814.45 stddev:23551.96 (489.2%) stddev-sans-first:23579.45 03:37:02 INFO - PROCESS | 20696 | Values: 818.6 844.8 847.7 911.0 923.0 17976.6 18549.9 18724.3 18814.9 19617.2 17559.3 17683.9 18062.5 18075.2 18113.2 802.5 819.8 822.0 822.9 823.8 685.6 706.8 790.7 807.4 810.7 866.9 867.1 868.7 869.2 869.3 863.6 864.1 864.2 864.3 865.0 528.7 540.1 603.1 611.8 617.4 16502.9 17291.2 17407.9 17453.2 17454.0 14798.5 15336.2 15498.9 15662.1 15727.2 13718.0 13763.0 13826.3 13880.7 13968.7 4402.4 4406.6 4411.0 4455.3 4463.5 82296.2 83111.6 83442.2 83926.0 83950.6 11841.7 11941.3 12008.3 12082.9 12288.9 22016.2 22100.6 22301.0 22531.1 22723.4 8690.7 8738.1 8837.4 8912.0 9060.7 4737.6 4742.4 4743.4 4821.9 4851.8 3660.4 3717.1 3754.5 3755.0 3760.1 600.9 604.8 609.6 613.2 622.1 762.8 764.8 784.4 786.9 786.9 10202.6 10402.8 10548.5 10591.3 10715.6 746.6 750.8 764.5 782.6 782.9 4807.0 4876.4 4903.4 4930.6 4940.4 63872.7 78213.6 85327.6 87232.6 87756.0 73951.3 74451.4 75212.0 76727.2 78377.2 61054.0 62262.0 62324.0 63378.4 63715.4 8999.5 9077.7 9250.2 9314.3 9317.9 46975.6 47239.3 48451.0 48592.0 48756.8 56471.4 56695.4 57392.0 57755.0 59181.4 11860.5 12493.5 12525.9 12622.9 13264.8 14865.2 15088.5 15460.0 15572.3 16063.8 14476.0 15136.8 15238.7 15321.5 15413.2 14734.4 15372.0 15439.3 15574.8 15723.2 14956.0 14967.3 14975.9 15175.3 15323.3 11115.8 11172.0 11179.4 11452.4 11473.0 12292.6 12408.9 12506.9 12793.7 12877.2 03:37:02 INFO - PROCESS | 20696 | -------- Summary: end -------- 03:37:02 INFO - PROCESS | 20696 | 03:37:03 INFO - TEST-INFO | 20696: exit 0 03:37:03 INFO - TEST-OK | dromaeo_css | took 1113451ms 03:37:03 INFO - TEST-START | kraken 03:37:03 INFO - operating with platform_type : linux_ 03:37:03 INFO - Initialising browser for kraken test... 03:37:03 INFO - TEST-INFO | started process 21010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3eiHeE/profile http://localhost:49191/getInfo.html) 03:37:07 INFO - PROCESS | 21010 | __metrics Screen width/height:1600/1200 03:37:07 INFO - PROCESS | 21010 | colorDepth:24 03:37:07 INFO - PROCESS | 21010 | Browser inner width/height: 1024/697 03:37:07 INFO - PROCESS | 21010 | __metrics 03:37:07 INFO - PROCESS | 21010 | 1486640227804 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 03:37:07 INFO - PROCESS | 21010 | 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" 03:37:07 INFO - PROCESS | 21010 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:37:08 INFO - TEST-INFO | 21010: exit 0 03:37:08 INFO - Browser initialized. 03:37:08 INFO - Running cycle 1/1 for kraken test... 03:37:08 INFO - TEST-INFO | started process 21100 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3eiHeE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:37:20 INFO - PROCESS | 21100 | RSS: Main: 137940992 03:37:20 INFO - PROCESS | 21100 | 03:37:23 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49191/tests/kraken/driver.html?testName=audio-beat-detection) 03:37:24 INFO - PROCESS | 21100 | RSS: Main: 184131584 03:37:24 INFO - PROCESS | 21100 | 03:37:26 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49191/tests/kraken/driver.html?testName=audio-dft) 03:37:27 INFO - PROCESS | 21100 | RSS: Main: 172556288 03:37:27 INFO - PROCESS | 21100 | 03:37:30 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49191/tests/kraken/driver.html?testName=audio-fft) 03:37:30 INFO - PROCESS | 21100 | RSS: Main: 263471104 03:37:30 INFO - PROCESS | 21100 | 03:37:32 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49191/tests/kraken/driver.html?testName=audio-oscillator) 03:37:33 INFO - PROCESS | 21100 | RSS: Main: 169848832 03:37:33 INFO - PROCESS | 21100 | 03:37:35 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49191/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:37:36 INFO - PROCESS | 21100 | RSS: Main: 172150784 03:37:36 INFO - PROCESS | 21100 | 03:37:38 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49191/tests/kraken/driver.html?testName=imaging-darkroom) 03:37:39 INFO - PROCESS | 21100 | RSS: Main: 211271680 03:37:39 INFO - PROCESS | 21100 | 03:37:42 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49191/tests/kraken/driver.html?testName=imaging-desaturate) 03:37:43 INFO - PROCESS | 21100 | RSS: Main: 209567744 03:37:43 INFO - PROCESS | 21100 | 03:37:46 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49191/tests/kraken/driver.html?testName=json-parse-financial) 03:37:46 INFO - PROCESS | 21100 | RSS: Main: 207630336 03:37:46 INFO - PROCESS | 21100 | 03:37:48 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49191/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:37:48 INFO - PROCESS | 21100 | RSS: Main: 170582016 03:37:48 INFO - PROCESS | 21100 | 03:37:50 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:37:51 INFO - PROCESS | 21100 | RSS: Main: 178405376 03:37:51 INFO - PROCESS | 21100 | 03:37:53 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:37:53 INFO - PROCESS | 21100 | RSS: Main: 186748928 03:37:53 INFO - PROCESS | 21100 | 03:37:56 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:37:57 INFO - PROCESS | 21100 | RSS: Main: 195596288 03:37:57 INFO - PROCESS | 21100 | 03:37:59 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:38:00 INFO - PROCESS | 21100 | RSS: Main: 192761856 03:38:00 INFO - PROCESS | 21100 | 03:38:01 INFO - PROCESS | 21100 | Cycle 1(1): loaded http://localhost:49191/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49191/tests/kraken/driver.html?testName=ai-astar) 03:38:02 INFO - PROCESS | 21100 | RSS: Main: 243732480 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | __start_tp_report 03:38:02 INFO - PROCESS | 21100 | _x_x_mozilla_page_load 03:38:02 INFO - PROCESS | 21100 | _x_x_mozilla_page_load_details 03:38:02 INFO - PROCESS | 21100 | |i|pagename|runs| 03:38:02 INFO - PROCESS | 21100 | |0;ai-astar;112;111;111;110;113;111;112;112;111;110 03:38:02 INFO - PROCESS | 21100 | |1;audio-beat-detection;146;119;119;172;122;120;117;117;136;119 03:38:02 INFO - PROCESS | 21100 | |2;audio-dft;162;161;161;161;162;160;160;159;168;169 03:38:02 INFO - PROCESS | 21100 | |3;audio-fft;74;74;77;104;77;76;75;76;99;75 03:38:02 INFO - PROCESS | 21100 | |4;audio-oscillator;83;83;108;83;84;83;93;83;84;95 03:38:02 INFO - PROCESS | 21100 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 03:38:02 INFO - PROCESS | 21100 | |6;imaging-darkroom;148;147;146;146;147;147;146;147;146;147 03:38:02 INFO - PROCESS | 21100 | |7;imaging-desaturate;96;96;96;96;96;95;96;95;95;95 03:38:02 INFO - PROCESS | 21100 | |8;json-parse-financial;48;48;48;48;48;50;51;49;49;48 03:38:02 INFO - PROCESS | 21100 | |9;json-stringify-tinderbox;63;65;64;65;64;64;63;63;63;64 03:38:02 INFO - PROCESS | 21100 | |10;stanford-crypto-aes;76;83;76;77;76;83;76;75;75;82 03:38:02 INFO - PROCESS | 21100 | |11;stanford-crypto-ccm;127;148;137;123;128;122;120;121;167;129 03:38:02 INFO - PROCESS | 21100 | |12;stanford-crypto-pbkdf2;159;154;155;159;154;159;155;160;162;157 03:38:02 INFO - PROCESS | 21100 | |13;stanford-crypto-sha256-iterative;60;60;62;60;60;61;61;61;70;61 03:38:02 INFO - PROCESS | 21100 | __end_tp_report 03:38:02 INFO - PROCESS | 21100 | __start_cc_report 03:38:02 INFO - PROCESS | 21100 | _x_x_mozilla_cycle_collect,1495 03:38:02 INFO - PROCESS | 21100 | __end_cc_report 03:38:02 INFO - PROCESS | 21100 | __startTimestamp1486640282199__endTimestamp 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | ------- Summary: start ------- 03:38:02 INFO - PROCESS | 21100 | Number of tests: 14 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:0.95 (0.9%) stddev-sans-first:0.97 03:38:02 INFO - PROCESS | 21100 | Values: 112.0 111.0 111.0 110.0 113.0 111.0 112.0 112.0 111.0 110.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:121.00 stddev:17.96 (14.8%) stddev-sans-first:17.93 03:38:02 INFO - PROCESS | 21100 | Values: 146.0 119.0 119.0 172.0 122.0 120.0 117.0 117.0 136.0 119.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#2] audio-dft Cycles:10 Average:162.30 Median:161.50 stddev:3.40 (2.1%) stddev-sans-first:3.61 03:38:02 INFO - PROCESS | 21100 | Values: 162.0 161.0 161.0 161.0 162.0 160.0 160.0 159.0 168.0 169.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#3] audio-fft Cycles:10 Average:80.70 Median:76.00 stddev:11.08 (14.6%) stddev-sans-first:11.48 03:38:02 INFO - PROCESS | 21100 | Values: 74.0 74.0 77.0 104.0 77.0 76.0 75.0 76.0 99.0 75.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:83.50 stddev:8.37 (10.0%) stddev-sans-first:8.69 03:38:02 INFO - PROCESS | 21100 | Values: 83.0 83.0 108.0 83.0 84.0 83.0 93.0 83.0 84.0 95.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 03:38:02 INFO - PROCESS | 21100 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 03:38:02 INFO - PROCESS | 21100 | Values: 148.0 147.0 146.0 146.0 147.0 147.0 146.0 147.0 146.0 147.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:38:02 INFO - PROCESS | 21100 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#8] json-parse-financial Cycles:10 Average:48.70 Median:48.50 stddev:1.06 (2.2%) stddev-sans-first:1.09 03:38:02 INFO - PROCESS | 21100 | Values: 48.0 48.0 48.0 48.0 48.0 50.0 51.0 49.0 49.0 48.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 03:38:02 INFO - PROCESS | 21100 | Values: 63.0 65.0 64.0 65.0 64.0 64.0 63.0 63.0 63.0 64.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:76.50 stddev:3.35 (4.4%) stddev-sans-first:3.48 03:38:02 INFO - PROCESS | 21100 | Values: 76.0 83.0 76.0 77.0 76.0 83.0 76.0 75.0 75.0 82.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:128.50 stddev:14.91 (11.6%) stddev-sans-first:15.70 03:38:02 INFO - PROCESS | 21100 | Values: 127.0 148.0 137.0 123.0 128.0 122.0 120.0 121.0 167.0 129.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.40 Median:159.00 stddev:2.80 (1.8%) stddev-sans-first:2.91 03:38:02 INFO - PROCESS | 21100 | Values: 159.0 154.0 155.0 159.0 154.0 159.0 155.0 160.0 162.0 157.0 03:38:02 INFO - PROCESS | 21100 | 03:38:02 INFO - PROCESS | 21100 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.00 stddev:3.03 (5.0%) stddev-sans-first:3.15 03:38:02 INFO - PROCESS | 21100 | Values: 60.0 60.0 62.0 60.0 60.0 61.0 61.0 61.0 70.0 61.0 03:38:02 INFO - PROCESS | 21100 | -------- Summary: end -------- 03:38:02 INFO - PROCESS | 21100 | 03:38:03 INFO - TEST-INFO | 21100: exit 0 03:38:03 INFO - TEST-OK | kraken | took 60190ms 03:38:03 INFO - SUITE-END | took 1173s 03:38:03 INFO - Completed test suite (00:19:33) 03:38:03 INFO - javascript benchmark 03:38:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3266.542684024236, 3283.589551306087, 3298.1589694627864, 3312.966870331296, 3337.449320862058, 5426.484483974026, 5484.670919744818, 5491.807786727465, 5495.697736624484, 5512.2834031575885, 5512.834614961547, 5547.44525547445, 5563.054280772269, 5565.41563135871, 5584.581156413265, 4940.752962351886, 4963.230699241623, 4989.151844186497, 4993.850184494471, 5062.594992400599, 4879.438864530589, 4901.2648102784515, 4997.0, 5044.2937988681615, 5054.166062599685, 4951.084049450845, 4957.727324997124, 4991.625628077891, 5036.370453693288, 5044.672096313746, 5082.364704411949, 5083.542481176675, 5087.847364579068, 5093.032323858473, 5113.411957624847, 4771.6898401603785, 4961.82633607822, 4977.477364876709, 5018.89962200754, 5037.395512538482, 5408.59435542332, 5473.863153421156, 5483.8080667176855, 5495.450454954473, 5557.249771280865, 5915.053591425351, 5927.585069045169, 5933.554983376221, 5940.257467816523, 5943.55423343251, 5995.400459954013, 6005.309389420186, 6018.217631707894, 6020.0, 6023.849403764941, 5444.428335024844, 5456.099743542351, 5458.099413596791, 5472.097103977879, 5586.776528938837, 5979.611325263844, 5980.611260268156, 5981.940180598225, 5990.400959904062, 6020.277566692025, 5721.141828725725, 5789.218455508537, 5792.82621521355, 5796.275465566804, 5799.82600521985, 5595.776168953278, 5608.85977850557, 5823.941760582425, 5843.444872737083, 5885.440883116097, 5841.4450627190345, 5892.6759028253855, 5895.056790913433, 5907.793227237026, 5927.8221653350465, 5883.588148829541, 5887.0, 5888.911666324922, 5892.499137573269, 5896.380880007623, 4377.299632058855, 4435.6229720473475, 4440.777961101997, 4469.486009108994, 4470.150671372428, 4029.194161167778, 4085.1421201547414, 4100.753954762664, 4124.546299907008, 4128.6284234418445, 5699.686517241592, 5701.515371193412, 5708.971455142698, 5711.942880571141, 5725.656460612377, 5783.074708046693, 5845.240118784531, 5876.706164691748, 5879.176915231871, 5994.400559943971, 5666.518345940559, 5671.064274394678, 5676.2337084493065, 5688.772449102073, 5711.600187986801, 5001.199808030698, 5026.447090820006, 5027.170516864677, 5027.4721154279005, 5061.544460998528, 5959.582829201914, 6004.969975150097, 6038.607490513103, 6104.084387341934, 6116.3577824328695, 5854.94145058561, 5901.616394934315, 5934.287885453773, 5937.406259374028, 5938.851528711817, 6182.227221597301, 6195.845103872439, 6215.160953271431, 6220.160278362363, 6221.502279817694, 5856.472917437451, 5918.73365698536, 5921.733521991435, 5922.822315330547, 5926.644401335933, 5844.707764611668, 5895.970520147372, 5898.056310990221, 5920.141579470866, 5945.3757355477055, 5857.970710146422, 5862.062070068769, 5862.736176872137, 5865.0, 5893.823185304448, 5848.064309710596, 5858.619189752652, 5858.736356863866, 5860.38465961085, 5860.912086318624, 5569.526590239875, 5610.18652295423, 5674.205611214351, 5718.085106382959, 5743.684097374685, 5787.045137552257, 5831.416858314305, 5836.474717275296, 5838.095095260242, 5858.0, 5766.3368712598585, 5773.740181691918, 5781.306243250837, 5781.971090144522, 5785.161151632906], "value": 5454.82585719623, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.2059116992848, 589.8171566814287, 618.4959258204563, 642.9324920883304, 666.9499787515938, 35645.0, 36272.27455450893, 40280.395794063115, 40307.99999999996, 46804.76597617016, 44497.33254001185, 50597.0, 50718.49281507184, 50746.49253507473, 50782.0, 9373.390729602554, 9559.28305377096, 9561.426314421122, 9561.521923903812, 9566.521673916312, 8514.574271286441, 8712.520811355373, 9361.48511831849, 9478.952605236998, 11044.668659940215, 11441.0, 11449.942750286236, 11453.31280123191, 11454.885451145465, 11462.254953428042, 11339.886601133965, 11395.430228488542, 11408.71478213043, 11418.657440276764, 11424.200305978586, 11186.496607652594, 11252.718682032933, 11253.212275140744, 11254.099672026217, 11257.831132533018, 46237.0, 46792.76603616994, 46915.0, 46985.295220571716, 47013.764931175465, 4377.671674624384, 4433.179861725591, 4461.620230780315, 4550.0, 4592.15045216636, 35131.0, 35285.823570881985, 35551.0, 35873.0, 36195.63804361975, 4416.867493975186, 4435.733855968652, 4457.665675074382, 4560.566746159109, 4679.391679081732, 254084.0, 255367.0, 256332.0, 257406.0, 257454.0, 60320.39679603148, 60390.69804650949, 60439.0, 60453.0, 60489.395106048374, 69883.0, 70322.29677703259, 70347.64826175837, 70501.64749176221, 70640.0, 19139.0, 19142.23431062742, 19142.521436963907, 19147.8085219146, 19288.8071119287, 14990.325435355435, 15095.32071056805, 15097.24513774307, 15100.169490677898, 15105.54683359479, 17096.914515427343, 17545.82454175442, 18613.906930465262, 18939.715904261448, 19079.141438635295, 6763.5265531413315, 6926.2034865990045, 7030.402415794612, 7147.392471639865, 7158.642067896584, 956.784723437221, 962.1965658675085, 967.7725734452398, 968.375397868371, 978.3445091788519, 31619.367612647617, 32866.17834554155, 33812.0, 33957.830210848784, 33970.0, 1064.5528877871313, 1140.8516892804048, 1158.8956993870424, 1165.597868735285, 1173.4484792147678, 16944.322227111024, 18112.909435452737, 18186.18162182705, 18198.727019094724, 18300.359487418144, 478862.0, 482559.0, 483664.0, 486067.0, 486472.0, 70286.0, 70534.29465705277, 70551.64724176346, 70584.2941570588, 70625.29374706188, 1475.0633347823994, 1483.5994281544138, 1514.5834895403852, 1517.8102737157856, 1521.7260893039138, 32565.34869302648, 33875.322493549495, 34112.0, 34209.657903420644, 34306.0, 58072.41927580839, 58669.0, 58674.119888200854, 58743.11885321809, 58803.0, 383511.0, 386094.0, 386724.0, 386825.0, 387407.0, 44826.327605086604, 45287.0, 45495.545044550454, 45545.77227113976, 45794.0, 44916.77541612271, 45900.77049614864, 48864.26703599378, 51372.74313628408, 51538.0, 42891.14217715691, 43212.567874320855, 43846.0, 45482.54517454783, 45743.313850292936, 42652.360214596185, 45165.0, 45403.09193816171, 45736.771316143204, 46075.53924460713, 45017.32474012827, 45869.08261834811, 45903.31145032894, 46094.53905460903, 46260.0, 37463.25073498569, 38978.0, 39343.606563933994, 39868.60131398649, 39914.60085399109, 29210.958086809696, 29491.85254073716, 30021.0, 30147.0, 30227.0], "value": 21705.28514861653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.8130794412374, 446.5958307731502, 448.18877831125667, 451.19236566545885, 464.7420681521756, 20278.391648250537, 21356.786432135694, 21801.01895414706, 21884.12463501462, 22581.661275080885, 14770.0, 16026.679466410666, 22750.203742869002, 23237.535249295004, 23399.766002339973, 5795.3335366432975, 5862.237909071815, 6011.128386383972, 6022.156898034257, 6026.0, 5744.052231381817, 5903.911441328362, 5906.497947674453, 5906.527477801766, 5906.852328691795, 5909.7636094556165, 5911.5270778337845, 5915.438033386821, 5919.348871624118, 6189.288231853327, 6494.155759751226, 6593.340665933392, 6593.835154121161, 6595.70319335631, 6596.340365963413, 6287.151234583318, 6412.583182093149, 6440.388163124542, 6453.064305675651, 6458.418742313214, 15940.043597384194, 16148.83851161497, 20417.4895627609, 26411.603825942435, 26753.06364277241, 2944.6024786653743, 2949.9705002950127, 2953.571732098855, 2960.8371539565314, 2995.3859458810894, 11316.773664526663, 11323.660290191225, 11487.885121148765, 11695.649130526013, 11814.940925295405, 1985.3746069987908, 2023.5952809438024, 2028.107632641633, 2071.4510654676524, 2095.8323334133233, 80122.19877801205, 89628.55185724095, 97278.51360743222, 97764.0, 98724.0, 12029.0, 12140.817887731691, 12317.199382040139, 12764.595894451602, 12998.740025199442, 17691.55771105707, 17732.0, 17755.11224438773, 17768.111594420487, 17772.111394430227, 9453.858192127123, 9485.762855928657, 9637.662681806103, 9746.171575416169, 9867.802643947081, 9258.87045979415, 9287.396319239228, 9327.766805829773, 9346.392484488486, 9548.6154507596, 5223.242629818655, 5360.839174824761, 5455.427180146107, 5521.861953451115, 5548.639338442988, 2124.840636952235, 2165.3071017274638, 2210.270610698498, 2226.1651880544796, 2247.6291411917177, 503.8261799679105, 509.69673044539184, 894.190757364586, 923.3767207135157, 932.5616960028775, 12717.364131793373, 12985.155964862253, 13419.261940593362, 13427.731445371039, 13618.046736728533, 533.703794394115, 534.1160379571814, 546.9808556700497, 555.361334465368, 556.6520924422236, 7022.719091236396, 7085.716571337089, 7208.207097219301, 7230.74692385764, 7372.889406658905, 656117.0, 664972.0508384659, 669142.0, 672778.0, 673003.0, 99193.50403247938, 113388.43305783418, 115331.0, 115986.0, 117522.41238793751, 526.0478533853737, 540.7269328988914, 540.8512659018801, 543.6466296907042, 544.3087279155451, 12126.211796233216, 12210.023198144305, 12234.938825305817, 12268.44791984345, 12480.563180288646, 41291.7935410321, 42145.78927105345, 44421.77789111034, 44869.0, 45063.54936450725, 61927.38072619216, 61975.0, 62577.374226257154, 64027.67986160226, 64829.67585162233, 9726.805463890541, 9754.31719779637, 9842.704718858446, 9910.405375677483, 9927.255455840985, 11760.76478470443, 14362.066465679698, 14490.85509144895, 14666.26668666541, 14725.116493010453, 11864.584739534075, 12222.144449888778, 12321.137520373488, 12417.875821241672, 12597.11820172579, 6162.7534898605345, 12361.258324500559, 12512.124151309321, 12532.87467125317, 12560.681128481378, 12360.38198090074, 12619.36903154857, 12623.179493332904, 12779.872201277869, 12847.29339886278, 9679.274054445805, 9995.500224988868, 10025.598976040877, 10174.338667986558, 10202.234832387749, 12521.499140034297, 12547.309897955334, 12547.560835370718, 12639.620811375673, 12814.103012789015], "value": 9352.123854521527, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.1636477201748, 1465.6555709408287, 1491.7389456845049, 1506.058713304185, 1508.5398953319236, 7788.805279868006, 8409.285210757085, 8688.218060374573, 8705.477671339724, 8905.020447750741, 8660.8700869487, 8709.869351959725, 8746.431481953668, 8747.518886461243, 8752.037275899645, 2109.5991761565256, 2169.3275084723787, 2384.3681424422584, 2421.33413311339, 2426.33275849142, 2331.25399872041, 2385.1771138957033, 2396.988015059931, 2400.7479214682467, 2401.687780588521, 2332.3935776698095, 2349.4126468382906, 2354.470244195051, 2359.9410014749596, 2362.232274510782, 2394.353524548362, 2397.6643269942224, 2420.3828023853803, 2422.321749910028, 2424.0, 2349.447879748258, 2361.5985282502015, 2377.6314671225987, 2380.345405013618, 2381.57131716291, 8655.0, 8676.219140277406, 8684.392092553524, 8732.257758090569, 8736.956315218447, 5281.287026251445, 5429.67421954684, 5430.782768689248, 5435.42927992559, 5436.972815135899, 3135.5766971459016, 3146.0, 3290.52287418323, 3306.718928891047, 3311.4536101543217, 1381.378379729126, 1409.1193004372267, 1422.1467119728184, 1472.3448065610808, 1517.0442621148716, 12200.816987745191, 12218.511259549607, 12226.694332641577, 12236.082293827909, 12252.693682657915, 4588.931166032512, 4604.40142781443, 4685.0395668887795, 4763.095011947788, 4799.592034677092, 10411.687649370531, 10480.213983951235, 10508.527116279785, 10554.525046372932, 10565.418901960466, 5439.265699130646, 5448.91826622601, 5449.100898351728, 5450.863728406822, 5451.100568406168, 4925.064237794879, 4954.083494553519, 5027.597792176617, 5053.9747301264, 5076.416212135578, 3851.171998020383, 3957.920841583152, 3969.0672691917375, 3999.1201935574127, 4010.3984402339884, 1223.2049168040667, 1223.3393967257582, 1231.408923716621, 1234.3643023842728, 1241.3606992398923, 929.6374413978554, 933.1601558597316, 935.3826474526779, 951.1534734086669, 952.7332346942867, 5700.515456186271, 5846.269216347957, 5885.705714714247, 5905.055191169392, 5987.850303742442, 638.0716058135401, 640.8173670503884, 644.9419552240226, 646.7736292297752, 659.7097277198113, 3727.142757165867, 3786.7159963002887, 3796.8101594920145, 3824.338687407527, 3828.3300422425964, 10405.53175107122, 10745.032947034804, 10766.192535559865, 10772.946135269274, 10814.188935829845, 10697.465126743631, 10734.194935379877, 10799.730006749736, 10855.077318427864, 10867.565297388173, 7900.170482099412, 8181.222783835526, 8253.33973282136, 8261.38039647029, 8316.584170791557, 5646.576506761927, 5758.971205143947, 5826.41735826414, 5827.125931110368, 5847.5906686533235, 9345.579448924951, 9533.570989305637, 9614.086661767122, 9724.95137524308, 9743.269254805777, 8408.537530435884, 9732.318737688294, 9926.950365248127, 9943.105120539187, 9948.204143668345, 7170.139583250043, 7579.583122928292, 7745.419093567893, 7759.922400776146, 7943.880841787262, 8516.489010659381, 8516.914830851612, 8541.487510749375, 8653.524056176971, 8692.130786921258, 7665.6550455228535, 7815.101263354787, 7854.41091918097, 8003.11965683786, 8125.106238313899, 7720.073591169095, 7846.60766961661, 7909.0, 8021.5187088774865, 8058.0, 6665.700043498151, 6713.697883595348, 6750.729970801113, 6800.727970881109, 6812.965935170292, 4906.6320025997475, 5030.0, 5044.722540260321, 5061.038402703474, 5123.28274041627, 5189.247559103985, 5202.089634313904, 5237.292965449616, 5291.629585929102, 5402.405735369028], "value": 4595.713206428077, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.6879112314587, 391.18242872396695, 391.4871518311013, 392.6230818414322, 397.53488418550296, 5173.767180476878, 5205.505476979685, 5271.393789714174, 5302.4697530247, 5336.626436149471, 5184.533391994729, 5284.969430960964, 5290.788368465257, 5310.583684423587, 5334.973325133369, 4121.938170927424, 4195.979020104911, 4211.642010429117, 4238.503234831262, 4259.616634502894, 4288.76411797351, 4291.914161716763, 4298.828046878121, 4301.2902871026245, 4318.438602981608, 4276.5295817460055, 4281.785910704468, 4284.871453856389, 4290.227759003394, 4314.899700576349, 4117.332480345465, 4177.3316269396755, 4295.828166873337, 4300.397944287803, 4304.203722311367, 3812.2566099610585, 3812.7140464464997, 3813.3517302058717, 3814.855543336988, 3840.7531594017532, 5162.786745114895, 5166.405863325728, 5191.766370513298, 5207.557357624606, 5222.4255331913455, 4552.860155726803, 4908.631852611033, 4918.213085906238, 4923.926141107886, 4956.207006878882, 5527.585431092684, 5556.388797232301, 5564.527015203713, 5567.359753628344, 5580.162975553659, 4019.577944315834, 4024.5371782245124, 4055.71609987301, 4059.573744756817, 4059.6752259819145, 5629.748100759688, 5774.653520788766, 5785.82642520725, 5797.507211886995, 5808.2449281593545, 4105.4662893823615, 4121.711480196357, 4129.132882094734, 4144.233316836395, 4147.751134931914, 4179.951823606471, 4194.958050419517, 4207.747535147901, 4208.873733787991, 4216.198922204771, 4696.264947198209, 4787.832425865078, 4789.736564488987, 4818.566329030404, 4823.903521929542, 5630.5214056805635, 5637.0, 5642.927843709764, 5651.265335506358, 5681.545476361881, 1403.9649008774659, 1404.2346920928119, 1406.606150277924, 1413.6570258254699, 1416.7874818777059, 921.2721949659693, 956.5456408206186, 965.9372140810825, 971.4025874087386, 988.3723835364596, 5218.347706536683, 5222.399424066281, 5240.764165612557, 5288.391834938954, 5291.031726205346, 5519.261432648703, 5526.723663816793, 5533.533613592401, 5541.556675465953, 5552.028395030853, 5117.744112794346, 5220.399654039758, 5295.708736019479, 5306.867328316745, 5322.627416080914, 2870.6842247352774, 2902.492063888811, 2906.6657334406814, 2926.536731634183, 2937.221636266391, 5611.438856114356, 5631.1271752878365, 5653.660780353192, 5668.0, 5678.943210567924, 5350.30446042012, 5597.80407685729, 5608.719564021864, 5625.54995600351, 5629.311206637993, 5662.348829884616, 5671.688057156814, 5672.914906276409, 5682.431756824284, 5704.229928959538, 5351.331083614548, 5524.1989911463415, 5527.585431092603, 5542.584306176972, 5592.245046918614, 5411.648242864201, 5433.4566543345345, 5469.374700143873, 5471.343438787371, 5478.233047373451, 5588.692621905834, 5699.544036477155, 5701.572382071444, 5703.887741890291, 5713.971649986666, 4063.9390409143293, 4084.182538770219, 4085.203385339949, 4098.221337945781, 4098.4876890388705, 5682.573806964411, 5688.175214593778, 5688.345840228427, 5688.943110568841, 5710.600257981901, 5445.50990410865, 5529.861753456034, 5532.557395408277, 5546.639468434539, 5563.748156664718, 5555.22226888244, 5559.916601250903, 5561.4716601922755, 5568.248286481273, 5574.888502230014, 5297.258383826191, 5528.009916430163, 5530.5022547969265, 5537.473939975695, 5542.3903370628395, 5581.553475721851, 5596.244506991667, 5650.576206784425, 5654.773809047592, 5660.433956604471, 5527.226188333556, 5535.584831137761, 5541.141123125922, 5565.38780734127, 5570.860728481658], "value": 4263.557739648712, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.5866137600511, 844.8479273730726, 847.6990668312752, 910.968116115936, 922.9538523073846, 17976.550586235335, 18549.907250463762, 18724.344647937323, 18814.905925470386, 19617.215311387565, 17559.29762809486, 17683.911580442113, 18062.5484362891, 18075.186616602252, 18113.1849066792, 802.4543310548839, 819.8114433680228, 822.0382152881143, 822.8518866604009, 823.7899335669396, 685.5817951049855, 706.780897921646, 790.731151408521, 807.3662175192475, 810.7040930060544, 866.8959724833029, 867.119873328572, 868.674247157316, 869.1525762381691, 869.313242538397, 863.6415887406719, 864.0927026622022, 864.191980498233, 864.2869632553137, 865.0484467086218, 528.6695815115553, 540.1169088540225, 603.1164344235672, 611.8348046027547, 617.4350469320566, 16502.917485412618, 17291.221895014627, 17407.91296043512, 17453.21460534279, 17454.0, 14798.48205312809, 15336.233188340539, 15498.922505387514, 15662.138582377964, 15727.22546588822, 13718.0, 13763.0, 13826.308684565833, 13880.722385552232, 13968.650783730387, 4402.361657559636, 4406.647468202536, 4410.955890441087, 4455.331700244957, 4463.531329210418, 82296.17703822945, 83111.584442078, 83442.16557834465, 83926.0, 83950.58024709838, 11841.703957401134, 11941.294860809396, 12008.279503229835, 12082.939585302192, 12288.877111228774, 22016.229431969805, 22100.557988840133, 22301.0, 22531.0987560499, 22723.431914202276, 8690.695825646071, 8738.126187381211, 8837.381383303236, 8911.955440222757, 9060.728178154666, 4737.644676649196, 4742.430908291027, 4743.430788305425, 4821.927671084936, 4851.830185943475, 3660.377735784923, 3717.1450566369886, 3754.530683664542, 3755.0, 3760.0787806986914, 600.8527910661916, 604.8366940926013, 609.6403122157948, 613.2242712968099, 622.1228068423592, 762.7940456076939, 764.8489024018816, 784.4312873166908, 786.92130786922, 786.9252421019994, 10202.642907498202, 10402.843957340647, 10548.525316360781, 10591.258611897247, 10715.587534765727, 746.6304179431216, 750.7785203365021, 764.4839733180081, 782.5734974438944, 782.8551717932087, 4806.975965120222, 4876.390451193601, 4903.387076615423, 4930.630202734809, 4940.382452193476, 63872.68063659652, 78213.60893195498, 85327.5733621328, 87232.56383718041, 87756.0, 73951.26048739551, 74451.41144017271, 75212.0, 76727.23272767312, 78377.21622783813, 61054.0, 62262.0, 62324.0, 63378.36621633724, 63715.36284637095, 8999.460032398078, 9077.727668169966, 9250.167484926389, 9314.25485961135, 9317.906820931705, 46975.55208964543, 47239.29141062818, 48451.0, 48592.0, 48756.756216218695, 56471.43528564662, 56695.43304567067, 57392.0, 57755.0, 59181.40818591759, 11860.525578976743, 12493.500259989862, 12525.874741252472, 12622.87377126217, 13264.80102798478, 14865.215914313898, 15088.54734357971, 15460.0, 15572.299246533707, 16063.75904361459, 14476.0, 15136.772948406004, 15238.695226095639, 15321.46374876874, 15413.229338532805, 14734.410623574937, 15372.0, 15439.305231264845, 15574.766378504104, 15723.213839308217, 14955.953083284066, 14967.251637418303, 14975.857397363026, 15175.317110730264, 15323.310451029953, 11115.777684446428, 11171.99452049287, 11179.441027948407, 11452.3701196435, 11473.0, 12292.569760058355, 12408.875911240772, 12506.937465312616, 12793.744125117631, 12877.16298440636], "value": 7692.694553828271, "unit": "score"}], "name": "dromaeo_css", "value": 7420.24050260689, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 111.0, 110.0, 113.0, 111.0, 112.0, 112.0, 111.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 119.0, 172.0, 122.0, 120.0, 117.0, 117.0, 136.0, 119.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 160.0, 160.0, 159.0, 168.0, 169.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 104.0, 77.0, 76.0, 75.0, 76.0, 99.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 108.0, 83.0, 84.0, 83.0, 93.0, 83.0, 84.0, 95.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 48.0, 48.0, 48.0, 50.0, 51.0, 49.0, 49.0, 48.0], "value": 48.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 65.0, 64.0, 64.0, 63.0, 63.0, 63.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 76.0, 77.0, 76.0, 83.0, 76.0, 75.0, 75.0, 82.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 148.0, 137.0, 123.0, 128.0, 122.0, 120.0, 121.0, 167.0, 129.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 155.0, 159.0, 154.0, 159.0, 155.0, 160.0, 162.0, 157.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 62.0, 60.0, 60.0, 61.0, 61.0, 61.0, 70.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1471.7000000000003, "alertThreshold": 2.0}]} 03:38:03 INFO - Return code: 0 03:38:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:38:03 ERROR - # TBPL SUCCESS # 03:38:03 INFO - Running post-action listener: _resource_record_post_action 03:38:03 INFO - [mozharness: 2017-02-09 11:38:03.484407Z] Finished run-tests step (success) 03:38:03 INFO - Running post-run listener: _resource_record_post_run 03:38:03 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 363286528; Read time: 0; Write time: 266708 03:38:03 INFO - TinderboxPrint: CPU usage
13.0% 03:38:03 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:38:03 INFO - TinderboxPrint: I/O write bytes / time
363,286,528 / 266,708 03:38:03 INFO - TinderboxPrint: CPU idle
8,259.9 (87.0%) 03:38:03 INFO - TinderboxPrint: CPU user
1,191.3 (12.5%) 03:38:03 INFO - TinderboxPrint: Swap in / out
0 / 0 03:38:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109355008; Read time: 0; Write time: 96156 03:38:03 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 253931520; Read time: 0; Write time: 170552 03:38:04 INFO - Running post-run listener: _upload_blobber_files 03:38:04 INFO - Blob upload gear active. 03:38:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:38:04 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 03:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:38:04 INFO - (blobuploader) - INFO - Open directory for files ... 03:38:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:38:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:38:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:38:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:38:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:38:07 INFO - (blobuploader) - INFO - Done attempting. 03:38:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:38:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:38:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:38:08 INFO - (blobuploader) - INFO - Done attempting. 03:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:38:09 INFO - (blobuploader) - INFO - Done attempting. 03:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:38:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:38:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:38:10 INFO - (blobuploader) - INFO - Done attempting. 03:38:10 INFO - (blobuploader) - INFO - Iteration through files over. 03:38:10 INFO - Return code: 0 03:38:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:38:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:38:10 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/982d5be88b5c1460ab1dcf0a07db0094fc5cd671ccb0c795ff1c7fa18bd97d72cff7130591b52fd88d23f5f52090f7d4028ea421115eae72886701de28bcab6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d29a61d39d9d1d2fd9bd734cdab633fbd403c0c13fcd70617efc30c7cd2a1b9fa0d4fd563a3d0d61ccca126c3e4bfe7686e54177f85f9343b6d3023340f971e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc5a77e0ede79b6a1357411f85845137cf4a664b24b6ce212ec413e937d653f0fa13f82107279011deeb93867d90015826d9c11b16b7f677abbda0a8bdbc982c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dd212a8e0ab06838f8a22c529212eff357c5a3937147d14049c2cbcb6d4a2a5bdb69ab51b964263a4033aba21abfb4c2d1b5184a33e344bce7bd3dafcaed8b3"} 03:38:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:38:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:38:10 INFO - Contents: 03:38:10 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/982d5be88b5c1460ab1dcf0a07db0094fc5cd671ccb0c795ff1c7fa18bd97d72cff7130591b52fd88d23f5f52090f7d4028ea421115eae72886701de28bcab6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d29a61d39d9d1d2fd9bd734cdab633fbd403c0c13fcd70617efc30c7cd2a1b9fa0d4fd563a3d0d61ccca126c3e4bfe7686e54177f85f9343b6d3023340f971e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc5a77e0ede79b6a1357411f85845137cf4a664b24b6ce212ec413e937d653f0fa13f82107279011deeb93867d90015826d9c11b16b7f677abbda0a8bdbc982c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dd212a8e0ab06838f8a22c529212eff357c5a3937147d14049c2cbcb6d4a2a5bdb69ab51b964263a4033aba21abfb4c2d1b5184a33e344bce7bd3dafcaed8b3"} 03:38:10 INFO - Running post-run listener: copy_logs_to_upload_dir 03:38:10 INFO - Copying logs to upload dir... 03:38:10 INFO - mkdir: /builds/slave/test/build/upload/logs 03:38:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.548775 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-09 03:38:09.932245) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 03:38:09.936013) ========= 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=ea65d483da93c827905c2090000001f5-1486639052.912678-658301881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/982d5be88b5c1460ab1dcf0a07db0094fc5cd671ccb0c795ff1c7fa18bd97d72cff7130591b52fd88d23f5f52090f7d4028ea421115eae72886701de28bcab6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d29a61d39d9d1d2fd9bd734cdab633fbd403c0c13fcd70617efc30c7cd2a1b9fa0d4fd563a3d0d61ccca126c3e4bfe7686e54177f85f9343b6d3023340f971e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc5a77e0ede79b6a1357411f85845137cf4a664b24b6ce212ec413e937d653f0fa13f82107279011deeb93867d90015826d9c11b16b7f677abbda0a8bdbc982c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dd212a8e0ab06838f8a22c529212eff357c5a3937147d14049c2cbcb6d4a2a5bdb69ab51b964263a4033aba21abfb4c2d1b5184a33e344bce7bd3dafcaed8b3"} build_url:https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/Cp8erld-SjWORy9nYS-vIg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/982d5be88b5c1460ab1dcf0a07db0094fc5cd671ccb0c795ff1c7fa18bd97d72cff7130591b52fd88d23f5f52090f7d4028ea421115eae72886701de28bcab6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d29a61d39d9d1d2fd9bd734cdab633fbd403c0c13fcd70617efc30c7cd2a1b9fa0d4fd563a3d0d61ccca126c3e4bfe7686e54177f85f9343b6d3023340f971e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc5a77e0ede79b6a1357411f85845137cf4a664b24b6ce212ec413e937d653f0fa13f82107279011deeb93867d90015826d9c11b16b7f677abbda0a8bdbc982c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8dd212a8e0ab06838f8a22c529212eff357c5a3937147d14049c2cbcb6d4a2a5bdb69ab51b964263a4033aba21abfb4c2d1b5184a33e344bce7bd3dafcaed8b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 03:38:09.965221) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:38:09.965976) ========= 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=ea65d483da93c827905c2090000001f5-1486639052.912678-658301881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:38:10.049959) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 03:38:10.050712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 03:38:10.051397) ========= ========= Total master_lag: 0.16 =========