builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1490556165.37 results: success (0) revision: cc53710589fb500610495da5258b7b9221edf681 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.367815) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.368933) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.369246) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.405209) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.405480) ========= 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=17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-26 12:22:45-- 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% 96.8M=0s 2017-03-26 12:22:46 (96.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130073 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.549588) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.549891) ========= 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=17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021922 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.599179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:45.599492) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc53710589fb500610495da5258b7b9221edf681 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev cc53710589fb500610495da5258b7b9221edf681 --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=17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-26 12:22:46,199 truncating revision to first 12 chars 2017-03-26 12:22:46,199 Setting DEBUG logging. 2017-03-26 12:22:46,199 attempt 1/10 2017-03-26 12:22:46,199 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc53710589fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-26 12:22:46,697 unpacking tar archive at: mozilla-central-cc53710589fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.852432 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:46.464694) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:46.464970) ========= script_repo_revision: cc53710589fb500610495da5258b7b9221edf681 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:46.465334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:46.465602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:22:46.474301) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-03-26 12:22:46.474570) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:22:47 INFO - MultiFileLogger online at 20170326 12:22:47 in /builds/slave/test 12:22:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:22:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:47 INFO - {'append_to_log': False, 12:22:47 INFO - 'base_work_dir': '/builds/slave/test', 12:22:47 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:22:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:47 INFO - 'branch': 'Firefox-Non-PGO', 12:22:47 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:47 INFO - 'config_files': ('talos/linux_config.py',), 12:22:47 INFO - 'default_actions': ('clobber', 12:22:47 INFO - 'read-buildbot-config', 12:22:47 INFO - 'download-and-extract', 12:22:47 INFO - 'populate-webroot', 12:22:47 INFO - 'create-virtualenv', 12:22:47 INFO - 'install', 12:22:47 INFO - 'run-tests'), 12:22:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:47 INFO - 'download_minidump_stackwalk': True, 12:22:47 INFO - 'download_symbols': 'ondemand', 12:22:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:47 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:47 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:47 INFO - 'gecko_profile': False, 12:22:47 INFO - 'gecko_profile_interval': 0, 12:22:47 INFO - 'installer_path': 'installer.exe', 12:22:47 INFO - 'log_level': 'info', 12:22:47 INFO - 'log_name': 'talos', 12:22:47 INFO - 'log_to_console': True, 12:22:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:22:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:22:47 INFO - 'opt_config_files': (), 12:22:47 INFO - 'pip_index': False, 12:22:47 INFO - 'suite': 'dromaeojs', 12:22:47 INFO - 'system_bits': '32', 12:22:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:47 INFO - 'title': 'talos-linux64-ix-009', 12:22:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:47 INFO - 'use_talos_json': True, 12:22:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:47 INFO - 'work_dir': 'build'} 12:22:47 INFO - [mozharness: 2017-03-26 19:22:47.174106Z] Running clobber step. 12:22:47 INFO - Running pre-action listener: _resource_record_pre_action 12:22:47 INFO - Running main action method: clobber 12:22:47 INFO - rmtree: /builds/slave/test/build 12:22:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:48 INFO - Running post-action listener: _resource_record_post_action 12:22:48 INFO - [mozharness: 2017-03-26 19:22:48.100777Z] Finished clobber step (success) 12:22:48 INFO - [mozharness: 2017-03-26 19:22:48.100877Z] Running read-buildbot-config step. 12:22:48 INFO - Running pre-action listener: _resource_record_pre_action 12:22:48 INFO - Running main action method: read_buildbot_config 12:22:48 INFO - Using buildbot properties: 12:22:48 INFO - { 12:22:48 INFO - "project": "", 12:22:48 INFO - "product": "firefox", 12:22:48 INFO - "who": "archaeopteryx@coole-files.de", 12:22:48 INFO - "installer_path": "public/build/target.tar.bz2", 12:22:48 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:22:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:22:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 12:22:48 INFO - "stage_platform": "linux64", 12:22:48 INFO - "basedir": "/builds/slave/test", 12:22:48 INFO - "buildnumber": 899, 12:22:48 INFO - "platform": "ubuntu64_hw", 12:22:48 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:22:48 INFO - "slavebuilddir": "test", 12:22:48 INFO - "taskId": "AqfX5oEoTUO_j93ht8EPXg", 12:22:48 INFO - "branch": "mozilla-central", 12:22:48 INFO - "script_repo_revision": "production", 12:22:48 INFO - "revision": "cc53710589fb500610495da5258b7b9221edf681", 12:22:48 INFO - "slavename": "talos-linux64-ix-009", 12:22:48 INFO - "repo_path": "mozilla-central" 12:22:48 INFO - } 12:22:48 INFO - Finding installer, test and symbols from parent task. 12:22:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AqfX5oEoTUO_j93ht8EPXg'}, attempt #1 12:22:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AqfX5oEoTUO_j93ht8EPXg'}, attempt #1 12:22:49 INFO - Task dependencies: MPKDCmepT6ejwiWPeDlEyQ 12:22:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ'}, attempt #1 12:22:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2 12:22:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/test_packages.json 12:22:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.crashreporter-symbols.zip 12:22:49 INFO - Running post-action listener: _resource_record_post_action 12:22:49 INFO - [mozharness: 2017-03-26 19:22:49.583378Z] Finished read-buildbot-config step (success) 12:22:49 INFO - [mozharness: 2017-03-26 19:22:49.583593Z] Running download-and-extract step. 12:22:49 INFO - Running pre-action listener: _resource_record_pre_action 12:22:49 INFO - Running main action method: download_and_extract 12:22:49 INFO - mkdir: /builds/slave/test/build/tests 12:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:49 INFO - trying https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.test_packages.json 12:22:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:22:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:22:50 INFO - Downloaded 986 bytes. 12:22:50 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:22:50 INFO - Using the following test package requirements: 12:22:50 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:22:50 INFO - u'common': [u'target.common.tests.zip'], 12:22:50 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:22:50 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:22:50 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:22:50 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:22:50 INFO - u'mozbase': [u'target.common.tests.zip'], 12:22:50 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:22:50 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:22:50 INFO - u'web-platform': [u'target.common.tests.zip', 12:22:50 INFO - u'target.web-platform.tests.zip'], 12:22:50 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:22:50 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:22:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.common.tests.zip 12:22:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:22:50 INFO - Fetch https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.common.tests.zip into memory 12:22:52 INFO - Expected file size: 58128620 12:22:52 INFO - Obtained file size: 58128620 12:23:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:23:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.common.tests.zip 12:23:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:23:02 INFO - Fetch https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.common.tests.zip into memory 12:23:03 INFO - Expected file size: 58128620 12:23:03 INFO - Obtained file size: 58128620 12:23:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.talos.tests.zip 12:23:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:23:14 INFO - Fetch https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.talos.tests.zip into memory 12:23:16 INFO - Expected file size: 14404171 12:23:16 INFO - Obtained file size: 14404171 12:23:16 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')]} 12:23:16 INFO - trying https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2 12:23:16 INFO - Downloading https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:23:18 INFO - Downloaded 62699520 bytes. 12:23:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2 12:23:18 INFO - mkdir: /builds/slave/test/properties 12:23:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:18 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:18 INFO - Contents: 12:23:18 INFO - build_url:https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2 12:23:18 INFO - Running post-action listener: _resource_record_post_action 12:23:18 INFO - Running post-action listener: set_extra_try_arguments 12:23:18 INFO - [mozharness: 2017-03-26 19:23:18.421285Z] Finished download-and-extract step (success) 12:23:18 INFO - [mozharness: 2017-03-26 19:23:18.421380Z] Running populate-webroot step. 12:23:18 INFO - Running pre-action listener: _resource_record_pre_action 12:23:18 INFO - Running main action method: populate_webroot 12:23:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:18 INFO - '--tppagecycles', 12:23:18 INFO - '7'], 12:23:18 INFO - 'tests': ['tsvgm']}}, 12:23:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['damp', 'tps']}, 12:23:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'tests': ['damp', 'tps']}, 12:23:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['dromaeo_dom']}, 12:23:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['basic_compositor_video']}, 12:23:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:23:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['a11yr', 12:23:18 INFO - 'ts_paint', 12:23:18 INFO - 'tpaint', 12:23:18 INFO - 'sessionrestore', 12:23:18 INFO - 'sessionrestore_no_auto_restore', 12:23:18 INFO - 'tabpaint']}, 12:23:18 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:18 INFO - 'ts_paint', 12:23:18 INFO - 'tpaint', 12:23:18 INFO - 'sessionrestore', 12:23:18 INFO - 'sessionrestore_no_auto_restore', 12:23:18 INFO - 'tabpaint']}, 12:23:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:18 INFO - 'ts_paint', 12:23:18 INFO - 'tpaint', 12:23:18 INFO - 'sessionrestore', 12:23:18 INFO - 'sessionrestore_no_auto_restore', 12:23:18 INFO - 'tabpaint']}, 12:23:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:18 INFO - 'ts_paint', 12:23:18 INFO - 'tpaint', 12:23:18 INFO - 'sessionrestore', 12:23:18 INFO - 'sessionrestore_no_auto_restore', 12:23:18 INFO - 'tabpaint']}, 12:23:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['a11yr', 12:23:18 INFO - 'ts_paint', 12:23:18 INFO - 'tpaint', 12:23:18 INFO - 'sessionrestore', 12:23:18 INFO - 'sessionrestore_no_auto_restore', 12:23:18 INFO - 'tabpaint']}, 12:23:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['a11yr', 12:23:18 INFO - 'ts_paint', 12:23:18 INFO - 'tpaint', 12:23:18 INFO - 'sessionrestore', 12:23:18 INFO - 'sessionrestore_no_auto_restore', 12:23:18 INFO - 'tabpaint']}, 12:23:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['tsvgx', 12:23:18 INFO - 'tsvgr_opacity', 12:23:18 INFO - 'tart', 12:23:18 INFO - 'tscrollx', 12:23:18 INFO - 'cart', 12:23:18 INFO - 'tsvg_static']}, 12:23:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:18 INFO - 'tsvgr_opacity', 12:23:18 INFO - 'tart', 12:23:18 INFO - 'tscrollx', 12:23:18 INFO - 'cart', 12:23:18 INFO - 'tsvg_static']}, 12:23:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'talos_options': ['--disable-e10s'], 12:23:18 INFO - 'tests': ['tp5o']}, 12:23:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:23:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'talos_options': ['--disable-e10s', 12:23:18 INFO - '--xperf_path', 12:23:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:18 INFO - 'tests': ['tp5n']}, 12:23:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:23:18 INFO - 'talos_options': ['--xperf_path', 12:23:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:18 INFO - 'tests': ['tp5n']}}, 12:23:18 INFO - 'talos.zip': {'path': '', 12:23:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:18 INFO - Running post-action listener: _resource_record_post_action 12:23:18 INFO - [mozharness: 2017-03-26 19:23:18.436825Z] Finished populate-webroot step (success) 12:23:18 INFO - [mozharness: 2017-03-26 19:23:18.436913Z] Running create-virtualenv step. 12:23:18 INFO - Running pre-action listener: _resource_record_pre_action 12:23:18 INFO - Running main action method: create_virtualenv 12:23:18 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:18 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 12:23:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:18 INFO - Using real prefix '/usr' 12:23:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:19 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:21 INFO - Installing pip.................done. 12:23:21 INFO - Return code: 0 12:23:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:23:21 INFO - Reading from file tmpfile_stdout 12:23:21 INFO - Output received: 12:23:21 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) 12:23:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:21 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')]} 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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')]} 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:21 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 12:23:21 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 12:23:21 INFO - Using env: {'DISPLAY': ':0', 12:23:21 INFO - 'HOME': '/home/cltbld', 12:23:21 INFO - 'LANG': 'en_US.UTF-8', 12:23:21 INFO - 'LANGUAGE': 'en_US:en', 12:23:21 INFO - 'LOGNAME': 'cltbld', 12:23:21 INFO - 'MAIL': '/var/mail/cltbld', 12:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:21 INFO - 'MOZ_NO_REMOTE': '1', 12:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:21 INFO - 'NO_EM_RESTART': '1', 12:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:21 INFO - 'PWD': '/builds/slave/test', 12:23:21 INFO - 'SHELL': '/bin/bash', 12:23:21 INFO - 'SHLVL': '1', 12:23:21 INFO - 'TERM': 'linux', 12:23:21 INFO - 'TMOUT': '86400', 12:23:21 INFO - 'USER': 'cltbld', 12:23:21 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:21 INFO - '_': '/tools/buildbot/bin/python'} 12:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:21 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 12:23:21 INFO - Cleaning up... 12:23:21 INFO - Return code: 0 12:23:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23:21 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')]} 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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')]} 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:21 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 12:23:21 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 12:23:21 INFO - Using env: {'DISPLAY': ':0', 12:23:21 INFO - 'HOME': '/home/cltbld', 12:23:21 INFO - 'LANG': 'en_US.UTF-8', 12:23:21 INFO - 'LANGUAGE': 'en_US:en', 12:23:21 INFO - 'LOGNAME': 'cltbld', 12:23:21 INFO - 'MAIL': '/var/mail/cltbld', 12:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:21 INFO - 'MOZ_NO_REMOTE': '1', 12:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:21 INFO - 'NO_EM_RESTART': '1', 12:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:21 INFO - 'PWD': '/builds/slave/test', 12:23:21 INFO - 'SHELL': '/bin/bash', 12:23:21 INFO - 'SHLVL': '1', 12:23:21 INFO - 'TERM': 'linux', 12:23:21 INFO - 'TMOUT': '86400', 12:23:21 INFO - 'USER': 'cltbld', 12:23:21 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:21 INFO - '_': '/tools/buildbot/bin/python'} 12:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:21 INFO - Downloading/unpacking psutil>=3.1.1 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:23 INFO - Installing collected packages: psutil 12:23:23 INFO - Running setup.py install for psutil 12:23:23 INFO - building 'psutil._psutil_linux' extension 12:23:23 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 12:23:24 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 12:23:24 INFO - building 'psutil._psutil_posix' extension 12:23:24 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 12:23:24 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 12:23:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:24 INFO - Successfully installed psutil 12:23:24 INFO - Cleaning up... 12:23:24 INFO - Return code: 0 12:23:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:24 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:24 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 12:23:24 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 12:23:24 INFO - Using env: {'DISPLAY': ':0', 12:23:24 INFO - 'HOME': '/home/cltbld', 12:23:24 INFO - 'LANG': 'en_US.UTF-8', 12:23:24 INFO - 'LANGUAGE': 'en_US:en', 12:23:24 INFO - 'LOGNAME': 'cltbld', 12:23:24 INFO - 'MAIL': '/var/mail/cltbld', 12:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:24 INFO - 'MOZ_NO_REMOTE': '1', 12:23:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:24 INFO - 'NO_EM_RESTART': '1', 12:23:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:24 INFO - 'PWD': '/builds/slave/test', 12:23:24 INFO - 'SHELL': '/bin/bash', 12:23:24 INFO - 'SHLVL': '1', 12:23:24 INFO - 'TERM': 'linux', 12:23:24 INFO - 'TMOUT': '86400', 12:23:24 INFO - 'USER': 'cltbld', 12:23:24 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:24 INFO - '_': '/tools/buildbot/bin/python'} 12:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:24 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 12:23:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:23:26 INFO - Installing collected packages: mozsystemmonitor 12:23:26 INFO - Running setup.py install for mozsystemmonitor 12:23:26 INFO - Successfully installed mozsystemmonitor 12:23:26 INFO - Cleaning up... 12:23:26 INFO - Return code: 0 12:23:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:26 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:26 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 12:23:26 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 12:23:26 INFO - Using env: {'DISPLAY': ':0', 12:23:26 INFO - 'HOME': '/home/cltbld', 12:23:26 INFO - 'LANG': 'en_US.UTF-8', 12:23:26 INFO - 'LANGUAGE': 'en_US:en', 12:23:26 INFO - 'LOGNAME': 'cltbld', 12:23:26 INFO - 'MAIL': '/var/mail/cltbld', 12:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:26 INFO - 'MOZ_NO_REMOTE': '1', 12:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:26 INFO - 'NO_EM_RESTART': '1', 12:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:26 INFO - 'PWD': '/builds/slave/test', 12:23:26 INFO - 'SHELL': '/bin/bash', 12:23:26 INFO - 'SHLVL': '1', 12:23:26 INFO - 'TERM': 'linux', 12:23:26 INFO - 'TMOUT': '86400', 12:23:26 INFO - 'USER': 'cltbld', 12:23:26 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:26 INFO - '_': '/tools/buildbot/bin/python'} 12:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:27 INFO - Downloading/unpacking jsonschema==2.5.1 12:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:29 INFO - warning: no files found matching '*.txt' 12:23:29 INFO - no previously-included directories found matching 'build' 12:23:29 INFO - no previously-included directories found matching 'dist' 12:23:29 INFO - no previously-included directories found matching '.git*' 12:23:29 INFO - Installing collected packages: jsonschema, functools32 12:23:29 INFO - Running setup.py install for functools32 12:23:29 INFO - warning: no files found matching '*.txt' 12:23:29 INFO - no previously-included directories found matching 'build' 12:23:29 INFO - no previously-included directories found matching 'dist' 12:23:29 INFO - no previously-included directories found matching '.git*' 12:23:29 INFO - Successfully installed jsonschema functools32 12:23:29 INFO - Cleaning up... 12:23:29 INFO - Return code: 0 12:23:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:29 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:29 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 12:23:29 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 12:23:29 INFO - Using env: {'DISPLAY': ':0', 12:23:29 INFO - 'HOME': '/home/cltbld', 12:23:29 INFO - 'LANG': 'en_US.UTF-8', 12:23:29 INFO - 'LANGUAGE': 'en_US:en', 12:23:29 INFO - 'LOGNAME': 'cltbld', 12:23:29 INFO - 'MAIL': '/var/mail/cltbld', 12:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:29 INFO - 'MOZ_NO_REMOTE': '1', 12:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:29 INFO - 'NO_EM_RESTART': '1', 12:23:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:29 INFO - 'PWD': '/builds/slave/test', 12:23:29 INFO - 'SHELL': '/bin/bash', 12:23:29 INFO - 'SHLVL': '1', 12:23:29 INFO - 'TERM': 'linux', 12:23:29 INFO - 'TMOUT': '86400', 12:23:29 INFO - 'USER': 'cltbld', 12:23:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:29 INFO - '_': '/tools/buildbot/bin/python'} 12:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:23:29 INFO - Cleaning up... 12:23:29 INFO - Return code: 0 12:23:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:29 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:29 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 12:23:29 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 12:23:29 INFO - Using env: {'DISPLAY': ':0', 12:23:29 INFO - 'HOME': '/home/cltbld', 12:23:29 INFO - 'LANG': 'en_US.UTF-8', 12:23:29 INFO - 'LANGUAGE': 'en_US:en', 12:23:29 INFO - 'LOGNAME': 'cltbld', 12:23:29 INFO - 'MAIL': '/var/mail/cltbld', 12:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:29 INFO - 'MOZ_NO_REMOTE': '1', 12:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:29 INFO - 'NO_EM_RESTART': '1', 12:23:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:29 INFO - 'PWD': '/builds/slave/test', 12:23:29 INFO - 'SHELL': '/bin/bash', 12:23:29 INFO - 'SHLVL': '1', 12:23:29 INFO - 'TERM': 'linux', 12:23:29 INFO - 'TMOUT': '86400', 12:23:29 INFO - 'USER': 'cltbld', 12:23:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:29 INFO - '_': '/tools/buildbot/bin/python'} 12:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:29 INFO - Downloading/unpacking blobuploader==1.2.4 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 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 12:23:29 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 12:23:31 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 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 12:23:32 INFO - Downloading docopt-0.6.1.tar.gz 12:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:32 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:32 INFO - Running setup.py install for blobuploader 12:23:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:32 INFO - Running setup.py install for requests 12:23:33 INFO - Running setup.py install for docopt 12:23:33 INFO - Successfully installed blobuploader requests docopt 12:23:33 INFO - Cleaning up... 12:23:33 INFO - Return code: 0 12:23:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:33 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')]} 12:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:33 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')]} 12:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:33 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:33 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 12:23:33 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 12:23:33 INFO - Using env: {'DISPLAY': ':0', 12:23:33 INFO - 'HOME': '/home/cltbld', 12:23:33 INFO - 'LANG': 'en_US.UTF-8', 12:23:33 INFO - 'LANGUAGE': 'en_US:en', 12:23:33 INFO - 'LOGNAME': 'cltbld', 12:23:33 INFO - 'MAIL': '/var/mail/cltbld', 12:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:33 INFO - 'MOZ_NO_REMOTE': '1', 12:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:33 INFO - 'NO_EM_RESTART': '1', 12:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:33 INFO - 'PWD': '/builds/slave/test', 12:23:33 INFO - 'SHELL': '/bin/bash', 12:23:33 INFO - 'SHLVL': '1', 12:23:33 INFO - 'TERM': 'linux', 12:23:33 INFO - 'TMOUT': '86400', 12:23:33 INFO - 'USER': 'cltbld', 12:23:33 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:33 INFO - '_': '/tools/buildbot/bin/python'} 12:23:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:33 INFO - Running setup.py (path:/tmp/pip-ffIA50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:33 INFO - Running setup.py (path:/tmp/pip-Z1JjhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:33 INFO - Running setup.py (path:/tmp/pip-CzDdlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:33 INFO - Running setup.py (path:/tmp/pip-5OOW4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:33 INFO - Running setup.py (path:/tmp/pip-MKOKsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:33 INFO - Running setup.py (path:/tmp/pip-37E7Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:33 INFO - Running setup.py (path:/tmp/pip-BiKSvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:34 INFO - Running setup.py (path:/tmp/pip-kTCoBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:34 INFO - Running setup.py (path:/tmp/pip-PQ7VVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:34 INFO - Running setup.py (path:/tmp/pip-X_LfM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:34 INFO - Running setup.py (path:/tmp/pip-V2vmRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:34 INFO - Running setup.py (path:/tmp/pip-8aq1p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:34 INFO - Running setup.py (path:/tmp/pip-yC74Rf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:34 INFO - Running setup.py (path:/tmp/pip-bNOuqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:34 INFO - Running setup.py (path:/tmp/pip-uCEyLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:34 INFO - Running setup.py (path:/tmp/pip-MnUMGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:34 INFO - Running setup.py (path:/tmp/pip-JjicOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:34 INFO - Running setup.py install for manifestparser 12:23:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:34 INFO - Running setup.py install for mozcrash 12:23:34 INFO - Running setup.py install for mozdebug 12:23:35 INFO - Running setup.py install for mozdevice 12:23:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Running setup.py install for mozfile 12:23:35 INFO - Running setup.py install for mozhttpd 12:23:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Running setup.py install for mozinfo 12:23:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Running setup.py install for mozInstall 12:23:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Running setup.py install for mozleak 12:23:35 INFO - Running setup.py install for mozlog 12:23:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Running setup.py install for moznetwork 12:23:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:35 INFO - Running setup.py install for mozprocess 12:23:36 INFO - Running setup.py install for mozprofile 12:23:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:36 INFO - Running setup.py install for mozrunner 12:23:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:36 INFO - Running setup.py install for mozscreenshot 12:23:36 INFO - Running setup.py install for moztest 12:23:36 INFO - Running setup.py install for mozversion 12:23:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:36 INFO - Cleaning up... 12:23:36 INFO - Return code: 0 12:23:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:36 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')]} 12:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:36 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')]} 12:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:36 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:36 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 12:23:36 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 12:23:36 INFO - Using env: {'DISPLAY': ':0', 12:23:36 INFO - 'HOME': '/home/cltbld', 12:23:36 INFO - 'LANG': 'en_US.UTF-8', 12:23:36 INFO - 'LANGUAGE': 'en_US:en', 12:23:36 INFO - 'LOGNAME': 'cltbld', 12:23:36 INFO - 'MAIL': '/var/mail/cltbld', 12:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:36 INFO - 'MOZ_NO_REMOTE': '1', 12:23:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:36 INFO - 'NO_EM_RESTART': '1', 12:23:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:36 INFO - 'PWD': '/builds/slave/test', 12:23:36 INFO - 'SHELL': '/bin/bash', 12:23:36 INFO - 'SHLVL': '1', 12:23:36 INFO - 'TERM': 'linux', 12:23:36 INFO - 'TMOUT': '86400', 12:23:36 INFO - 'USER': 'cltbld', 12:23:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:36 INFO - '_': '/tools/buildbot/bin/python'} 12:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:36 INFO - Running setup.py (path:/tmp/pip-Db0s5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:36 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)) 12:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:36 INFO - Running setup.py (path:/tmp/pip-ZLItVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:36 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)) 12:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:36 INFO - Running setup.py (path:/tmp/pip-G8tLSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:37 INFO - Running setup.py (path:/tmp/pip-TY_7lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:37 INFO - Running setup.py (path:/tmp/pip-AHBhun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:37 INFO - Running setup.py (path:/tmp/pip-qOvvfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:37 INFO - Running setup.py (path:/tmp/pip-Qr__1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:37 INFO - Running setup.py (path:/tmp/pip-E7o4t_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:37 INFO - Running setup.py (path:/tmp/pip-oREOWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:37 INFO - Running setup.py (path:/tmp/pip-gFvKng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:37 INFO - Running setup.py (path:/tmp/pip-CuITVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:37 INFO - Running setup.py (path:/tmp/pip-f33ics-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:37 INFO - Running setup.py (path:/tmp/pip-N7AxYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:37 INFO - Running setup.py (path:/tmp/pip-T008NG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:37 INFO - Running setup.py (path:/tmp/pip-aIrgoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:37 INFO - Running setup.py (path:/tmp/pip-87ana9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:37 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)) 12:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:37 INFO - Running setup.py (path:/tmp/pip-wl2rCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:38 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)) 12:23:38 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)) 12:23:38 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)) 12:23:38 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)) 12:23:38 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)) 12:23:38 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)) 12:23:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:39 INFO - Downloading blessings-1.6.tar.gz 12:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:40 INFO - Installing collected packages: blessings 12:23:40 INFO - Running setup.py install for blessings 12:23:40 INFO - Successfully installed blessings 12:23:40 INFO - Cleaning up... 12:23:40 INFO - Return code: 0 12:23:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:40 INFO - Reading from file tmpfile_stdout 12:23:40 INFO - Current package versions: 12:23:40 INFO - argparse == 1.2.1 12:23:40 INFO - blessings == 1.6 12:23:40 INFO - blobuploader == 1.2.4 12:23:40 INFO - docopt == 0.6.1 12:23:40 INFO - functools32 == 3.2.3-2 12:23:40 INFO - jsonschema == 2.5.1 12:23:40 INFO - manifestparser == 1.1 12:23:40 INFO - mozInstall == 1.12 12:23:40 INFO - mozcrash == 1.0 12:23:40 INFO - mozdebug == 0.1 12:23:40 INFO - mozdevice == 0.49 12:23:40 INFO - mozfile == 1.2 12:23:40 INFO - mozhttpd == 0.7 12:23:40 INFO - mozinfo == 0.9 12:23:40 INFO - mozleak == 0.1 12:23:40 INFO - mozlog == 3.4 12:23:40 INFO - moznetwork == 0.27 12:23:40 INFO - mozprocess == 0.25 12:23:40 INFO - mozprofile == 0.28 12:23:40 INFO - mozrunner == 6.13 12:23:40 INFO - mozscreenshot == 0.1 12:23:40 INFO - mozsystemmonitor == 0.3 12:23:40 INFO - moztest == 0.8 12:23:40 INFO - mozversion == 1.4 12:23:40 INFO - psutil == 3.1.1 12:23:40 INFO - requests == 1.2.3 12:23:40 INFO - wsgiref == 0.1.2 12:23:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:40 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 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:40 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 12:23:40 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 12:23:40 INFO - Using env: {'DISPLAY': ':0', 12:23:40 INFO - 'HOME': '/home/cltbld', 12:23:40 INFO - 'LANG': 'en_US.UTF-8', 12:23:40 INFO - 'LANGUAGE': 'en_US:en', 12:23:40 INFO - 'LOGNAME': 'cltbld', 12:23:40 INFO - 'MAIL': '/var/mail/cltbld', 12:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:40 INFO - 'MOZ_NO_REMOTE': '1', 12:23:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:40 INFO - 'NO_EM_RESTART': '1', 12:23:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:40 INFO - 'PWD': '/builds/slave/test', 12:23:40 INFO - 'SHELL': '/bin/bash', 12:23:40 INFO - 'SHLVL': '1', 12:23:40 INFO - 'TERM': 'linux', 12:23:40 INFO - 'TMOUT': '86400', 12:23:40 INFO - 'USER': 'cltbld', 12:23:40 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:40 INFO - '_': '/tools/buildbot/bin/python'} 12:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 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)) 12:23:40 INFO - Cleaning up... 12:23:40 INFO - Return code: 0 12:23:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1254a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, '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': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', '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 12:23:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:23:40 INFO - Using env: {'DISPLAY': ':0', 12:23:40 INFO - 'HOME': '/home/cltbld', 12:23:40 INFO - 'LANG': 'en_US.UTF-8', 12:23:40 INFO - 'LANGUAGE': 'en_US:en', 12:23:40 INFO - 'LOGNAME': 'cltbld', 12:23:40 INFO - 'MAIL': '/var/mail/cltbld', 12:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:40 INFO - 'MOZ_NO_REMOTE': '1', 12:23:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:40 INFO - 'NO_EM_RESTART': '1', 12:23:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:40 INFO - 'PWD': '/builds/slave/test', 12:23:40 INFO - 'SHELL': '/bin/bash', 12:23:40 INFO - 'SHLVL': '1', 12:23:40 INFO - 'TERM': 'linux', 12:23:40 INFO - 'TMOUT': '86400', 12:23:40 INFO - 'USER': 'cltbld', 12:23:40 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:40 INFO - '_': '/tools/buildbot/bin/python'} 12:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:23:40 INFO - Cleaning up... 12:23:40 INFO - Return code: 0 12:23:40 INFO - Running post-action listener: _resource_record_post_action 12:23:40 INFO - Running post-action listener: _start_resource_monitoring 12:23:40 INFO - Starting resource monitoring. 12:23:40 INFO - [mozharness: 2017-03-26 19:23:40.960971Z] Finished create-virtualenv step (success) 12:23:40 INFO - [mozharness: 2017-03-26 19:23:40.961513Z] Running install step. 12:23:40 INFO - Running pre-action listener: _resource_record_pre_action 12:23:40 INFO - Running main action method: install 12:23:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:41 INFO - Reading from file tmpfile_stdout 12:23:41 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:41 INFO - Reading from file tmpfile_stdout 12:23:41 INFO - Output received: 12:23:41 INFO - Usage: mozinstall [options] installer 12:23:41 INFO - Options: 12:23:41 INFO - -h, --help show this help message and exit 12:23:41 INFO - -d DEST, --destination=DEST 12:23:41 INFO - Directory to install application into. [default: 12:23:41 INFO - "/builds/slave/test"] 12:23:41 INFO - --app=APP Application being installed. [default: firefox] 12:23:41 INFO - mkdir: /builds/slave/test/build/application 12:23:41 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'] 12:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:23:56 INFO - Reading from file tmpfile_stdout 12:23:56 INFO - Output received: 12:23:56 INFO - /builds/slave/test/build/application/firefox/firefox 12:23:56 INFO - Running post-action listener: _resource_record_post_action 12:23:56 INFO - [mozharness: 2017-03-26 19:23:56.920874Z] Finished install step (success) 12:23:56 INFO - [mozharness: 2017-03-26 19:23:56.921023Z] Running run-tests step. 12:23:56 INFO - Running pre-action listener: _resource_record_pre_action 12:23:56 INFO - Running main action method: run_tests 12:23:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:56 INFO - Python 2.7.3 12:23:56 INFO - Return code: 0 12:23:56 INFO - grabbing minidump binary from tooltool 12:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:56 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 0x13f7290>, '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 0x13f7b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f8040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:56 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 12:23:56 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 12:23:56 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 12:23:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:56 INFO - Return code: 0 12:23:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:23:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:56 INFO - ENV: RUST_BACKTRACE is now 1 12:23:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:23:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 12:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 12:23:57 INFO - Using env: {'DISPLAY': ':0', 12:23:57 INFO - 'HOME': '/home/cltbld', 12:23:57 INFO - 'LANG': 'en_US.UTF-8', 12:23:57 INFO - 'LANGUAGE': 'en_US:en', 12:23:57 INFO - 'LOGNAME': 'cltbld', 12:23:57 INFO - 'MAIL': '/var/mail/cltbld', 12:23:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:57 INFO - 'MOZ_NO_REMOTE': '1', 12:23:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:57 INFO - 'NO_EM_RESTART': '1', 12:23:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:57 INFO - 'PWD': '/builds/slave/test', 12:23:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:57 INFO - 'RUST_BACKTRACE': '1', 12:23:57 INFO - 'SHELL': '/bin/bash', 12:23:57 INFO - 'SHLVL': '1', 12:23:57 INFO - 'TERM': 'linux', 12:23:57 INFO - 'TMOUT': '86400', 12:23:57 INFO - 'USER': 'cltbld', 12:23:57 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285', 12:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:57 INFO - '_': '/tools/buildbot/bin/python'} 12:23:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 12:23:57 INFO - mozversion application_buildid: 20170326185025 12:23:57 INFO - mozversion application_changeset: cc53710589fb500610495da5258b7b9221edf681 12:23:57 INFO - mozversion application_display_name: Nightly 12:23:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:57 INFO - mozversion application_name: Firefox 12:23:57 INFO - mozversion application_remotingname: firefox 12:23:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:23:57 INFO - mozversion application_vendor: Mozilla 12:23:57 INFO - mozversion application_version: 55.0a1 12:23:57 INFO - mozversion platform_buildid: 20170326185025 12:23:57 INFO - mozversion platform_changeset: cc53710589fb500610495da5258b7b9221edf681 12:23:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:23:57 INFO - mozversion platform_version: 55.0a1 12:23:57 INFO - using testdate: 1490556237 12:23:57 INFO - actual date: 1490556237 12:23:57 INFO - starting webserver on 'localhost:53662' 12:23:57 INFO - SUITE-START | Running 2 tests 12:23:57 INFO - TEST-START | dromaeo_css 12:23:57 INFO - operating with platform_type : linux_ 12:23:57 INFO - Initialising browser for dromaeo_css test... 12:23:57 INFO - TEST-INFO | started process 1821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXtsb3I/profile http://localhost:53662/getInfo.html) 12:24:01 INFO - PID 1821 | __metrics Screen width/height:1600/1200 12:24:01 INFO - PID 1821 | colorDepth:24 12:24:01 INFO - PID 1821 | Browser inner width/height: 1024/697 12:24:01 INFO - PID 1821 | __metrics 12:24:02 ERROR - PID 1821 | 1490556242549 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) 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:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924: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 12:24:02 INFO - PID 1821 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:24:02 INFO - PID 1821 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:24:03 INFO - TEST-INFO | 1821: exit 0 12:24:03 INFO - Browser initialized. 12:24:03 INFO - Running cycle 1/1 for dromaeo_css test... 12:24:03 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXtsb3I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:15 INFO - PID 1913 | RSS: Main: 149405696 12:24:15 INFO - PID 1913 | 12:27:22 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:53662/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53662/tests/dromaeo/cssquery-ext.html) 12:27:24 INFO - PID 1913 | RSS: Main: 322568192 12:27:24 INFO - PID 1913 | 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 12:29:05 INFO - PID 1913 | 12:29:05 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:05 INFO - PID 1913 | Full stack: 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 12:29:05 INFO - PID 1913 | 12:29:05 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:05 INFO - PID 1913 | Full stack: 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 12:29:05 INFO - PID 1913 | 12:29:05 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:05 INFO - PID 1913 | Full stack: 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | ************************* 12:29:05 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 12:29:05 INFO - PID 1913 | 12:29:05 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:05 INFO - PID 1913 | Full stack: 12:29:05 INFO - PID 1913 | ************************* 12:30:27 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:53662/tests/dromaeo/cssquery-ext.html (next: http://localhost:53662/tests/dromaeo/cssquery-jquery.html) 12:30:27 INFO - PID 1913 | RSS: Main: 234487808 12:30:27 INFO - PID 1913 | 12:33:29 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:53662/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53662/tests/dromaeo/cssquery-mootools.html) 12:33:30 INFO - PID 1913 | RSS: Main: 182882304 12:33:30 INFO - PID 1913 | 12:36:32 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:53662/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53662/tests/dromaeo/cssquery-prototype.html) 12:36:33 INFO - PID 1913 | RSS: Main: 226390016 12:36:33 INFO - PID 1913 | 12:39:36 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:53662/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53662/tests/dromaeo/cssquery-yui.html) 12:39:37 INFO - PID 1913 | RSS: Main: 187703296 12:39:37 INFO - PID 1913 | 12:42:40 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:53662/tests/dromaeo/cssquery-yui.html (next: http://localhost:53662/tests/dromaeo/cssquery-dojo.html) 12:42:41 INFO - PID 1913 | RSS: Main: 271466496 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | __start_tp_report 12:42:41 INFO - PID 1913 | _x_x_mozilla_page_load 12:42:41 INFO - PID 1913 | _x_x_mozilla_page_load_details 12:42:41 INFO - PID 1913 | |i|pagename|runs| 12:42:41 INFO - PID 1913 | |0;dojo.html;2700.3519155402705;2700.5949107633865;2739.041335532564;2745.9725402745967;2753.876075576599;5068.5945124390055;5148.794048238066;5221.06020916235;5236.423993360732;5303.257543943846;5234.73826308685;5238.292830467895;5275.287836142129;5284.603654725892;5296.6292359534755;4718.693284936468;4757.571818536331;4780.259059845721;4832.420109586837;4890.902181956359;4770.093682200406;4778.546038126373;4798.928016079762;4838.612910967132;4896.412430508344;4679.742614156254;4701.78841952113;4708.4820669726305;4720.740359280238;4723.267893476483;4728.172569800271;4728.527147285244;4729.101470720587;4730.290456431529;4733.0533893221145;4669.976650116761;4681.180793361148;4702.36518070063;4702.788374523155;4708.317293992352;5169.793208271664;5187.896242075175;5253.238280449352;5258.342707161605;5260.894782104337;5617;5617.045102332572;5617.4382561743905;5621.7751289948355;5623.634463759883;5537.695426751527;5603.635763675348;5668.971655141698;5676.744546495418;5716.428357164292;5205.427402985669;5220.660657057279;5261.13191323435;5304.549113325334;5305.973470132702;5484.588655850827;5856.970715146397;5859.1797148399255;5860.8534786629825;5867.354590994987;5482.890342193134;5497.120460726344;5587.441255874462;5628.212050312959;5633.324001119892;5650.208970744099;5652.547796176296;5655.519280861173;5658.292713410824;5659.462351076641;5536;5547.306586676666;5554.527865131428;5603.411641777555;5604.6917419541505;5454.699991500505;5509.256250406143;5512.4211957744665;5540.058190107691;5542.418046105182;4619.376384188159;4636.188666983332;4653.162430762428;4669.229577119737;4670.509596492319;4012.6990475714438;4022.0749227677798;4025.4364388985564;4065.3495440729935;4070.7150499465347;4808.639352048541;4811.4707382187935;4818.325434439182;4818.807247710122;4860.659753817199;5537.224788529609;5537.390887002426;5593.356763972114;5601.719914004283;5637.154426836008;4827.131116399082;4828.517148285144;4831.154547954165;4843.39457567804;4874.731889746098;5061.544460998454;5061.924071138936;5064.696118232918;5065.265536497261;5066.822661206914;5180.197069454241;5232.816851410183;5312.681239125664;5325.786968521215;5381.461853814587;5590.972045139748;5625.353084395347;5662.094064949588;5670.8015219467125;5693.971530142323;5306.52241298285;5323.281357016908;5324.707141107121;5324.787008519771;5326.36083669962;5553.8611534710335;5612.102063669793;5615.32616086056;5619.269494965628;5667.20659107717;5502;5539.279893613804;5539.750711217925;5540.279763630702;5545.057340252086;5400.83797486076;5412.78348866041;5439.673619582838;5453.209284653783;5471.206675032018;5508.3114610673665;5517.917231241454;5525.696086715269;5526.696031718294;5566.025945459609;5429.995450841607;5441.673499589879;5446.400895901527;5449.04641687711;5456.045192091288;5414.133738601805;5463.863403414787;5484.561235101102;5547.667139971614;5582.609217354746;5416.972915135557;5441.347038355423;5442.673439593637;5478.643888147258;5485.917711234255 12:42:41 INFO - PID 1913 | |1;ext.html;613.3284053960914;698.1761521404742;715.1346870286955;1070.0636942675164;1085.104788549447;24885.75114248857;25517.74482255177;25523.744762552393;25524.61713074303;25534;30576.3884722306;31097.84451077747;31104.222394440094;31107.06678799634;31121.533177002362;7181.497295189339;7353.963230183842;7360.484766066377;7528.209537998513;7530.058742657168;5605;5677.545796336304;7424;7551.848963020736;7561.054868141482;7120.608366539838;7273.054502914613;7275;7298.197198308182;7346.889796653055;7320.926790732105;7350.852982940365;7379.852402951965;7396.297351751575;7419.4435417343375;5557.305336832896;5666.943330566724;7336.376408005326;7434.814129646748;7450.254974502506;28612.284692882637;29040.128796135938;29113.854430727923;29203.12390628263;31309.060728177963;2642.775364094054;2645.8280211786173;2647.9338016549546;2652.3236574673333;2682.383051898055;23385.298441046794;23604.409889752722;23664.40838979022;23738;23920.88039559791;2380.8928598213124;2389.545986262622;2404.302752201859;2416.311351264881;2423.2730180945823;150089.24955375263;150661;151391.24304378408;152091;153038;41793.164136717096;41806.790966045584;41856;41877.371839422434;41905.79047104745;43227;43264.134717305475;43270.35094473585;43345.56654433478;43383.34924976128;12723.745525089631;12975.935120324526;13005.479780808662;13015.804762928563;13018.414171362312;10409.27135100551;10410.167186625204;10482.475876206312;10490.475476226158;10498.685039448828;9704.660336888175;9957.352772069793;10715.892841071489;11267.605633802777;11547.826782598268;4678.391809064868;4682.648801339845;4740.834070807505;4765.142274390573;4866.562009419169;868.2649653591112;887.3123329419751;890.5947793753827;910.4355299714088;921.2767977137914;19115.256096952715;19934.90032549857;19977.20091196336;20056.197752089753;20201.292954746514;970.6117552978865;976.8496598469878;977.6480467031863;982.8280050991048;992.2607657295362;10273.691789246335;10374.948125259325;10633.5214915329;10680.572777088984;10749.08632766208;198272;199237;199777;200023;200224;40913;40963.59036409598;40986.590134098275;41058.9735256615;41087.794561027;1176.1061593189067;1234.1916044990546;1246.650937737435;1251.9812202816966;1264.1088032936757;18251.908740456212;18429.157084291463;19234.326798561982;19577.70633440471;19618.117184726438;29239;29248.707512924597;29434.264143396587;29483;29565.704342956295;204628;205202;205649;206433;206791;27014.324641883322;27129.72870271351;27199.72800271972;27369.86315068412;27396.58905116385;22862.885685571466;30177.396452071276;30589.388212235186;30768;34433;23814.880925595262;26609.201723948314;27192;27566.862165689847;28278.85860570684;28864.42271154607;28899.56650650284;28990.275243118245;29305;30016.249593759458;34608;34628.134296642784;35288.11779705528;35730.46404303885;35732.4640130393;35607.643923560434;35700.28599427945;35914.28171436609;36083.37624806943;36841.815790920875;23386.298411047697;24550.386240344134;24793.504129917663;24911.377215569755;25207.24378268655 12:42:41 INFO - PID 1913 | |2;jquery.html;411.63775877228016;412.9793510324483;413.6236025217052;418.098996662193;437.78548511229485;16347.182640867968;17201.741973870387;17777.733333999968;17887.91056044718;17895.19471623777;17621.118944052778;17875.10624468778;17950;18645.29643201738;18817.059147042663;5513.393526712059;5571.331440227178;5588.469095435948;5612.831615051555;5775.826725198251;5585.050541407971;5686.3744988051285;5696.08862581987;5696.6866822324755;5700.030994730885;5357.4106848246665;5505.559555235592;5539.750711218005;5658.066419040853;5667.5749318801045;5488.423715509894;5496.367917689477;5518.91721624176;5527.44725527446;5528.55771538276;4888.802243450342;4981.750912454363;4984.40187177541;4992.6754760940785;5004.074246264453;21066.89466552673;21335.466613334636;21885.234016809336;22277.55444891093;22332.33003009912;1594.4339759385482;1605.2776250687236;1615.9272832722556;1622.6267958369524;1646.8270831562752;13607.183568985893;14218.573442796733;14824.3329050193;14995.925020374938;15325.080495170325;1702.6935151672817;1741.538492299529;1765.7174852023743;1791.6237590106093;1821.5901422180036;78915.6054219731;82328.58835705843;83927.58036209841;85760.57119714424;85856;10274.434906080238;10561.947190264153;10596.50315395933;10703.304285221435;10724;14296.928515357356;14362.497312594009;14364.138151710931;14400.711985760227;14423.278836058154;7083.6458177090935;7236.710531578783;7260.346568808833;7471.364933980563;7482.1021477423055;6717.5297729159465;6743.932560674429;6915.550489218184;6928.688209030605;6966.79099627012;6657.700403481855;6767;6804.353586409283;6831.965840170867;6920.5847649140205;1922.528980399811;1927.9807201928081;1933.9709904351446;1951.317039036326;2095.182878677317;829.2371018662844;847.9152084791472;860.2515811244258;868.2576397180446;873.057098333798;16720.41478548245;17101.65796684084;17566.297348105934;17723;18027.27890884376;871.5729292646557;878.6221924572495;888.1695614600369;891;897.0580890065405;9145.405548639317;9425.575849086808;9693;9728.319017668837;9876.160526355341;435942;465836;469363;471252;480901;66926.33073669298;84017.57991210127;85632.57183714042;92456;92731;1059.0997651995897;1106.435717783936;1110.7556337605552;1116.9888301116885;1119.9832002519968;16509.33962641481;16567.591754700985;16950.830491694924;17483.82516174873;18227.088645567936;40176.59823401846;40653.796731016155;40933.79533102315;40959.38560921529;42011.579884200764;45866;46478.53521464742;53555;54384.184237235684;54748.72625637006;7396.778096657109;7651.770446886602;7723.459357844897;7940.483868548526;8155.5922203888385;12103.75792484163;12175.33035683046;12352.938235309126;12402.193857399601;12451.626451206115;10304.896951030394;10588.576456941637;10651.496898229065;10654.360738355723;10880.347179169275;10050.798984020132;10148.340357876714;10169.084782369622;10288.228382871464;10370.481475926324;10408.167346612101;10498.790024199321;10541.78916421652;10676.985686360094;10708.946455267675;7780.766577002699;7798.571078590732;7923.841523169389;7942.205779422012;7988.400869934662;5460.180972854104;10070.597176112873;10251.58993640246;10342.586296548054;10392.896071039193 12:42:41 INFO - PID 1913 | |3;mootools.html;1105.2926127278538;1248.5130798988394;1312;1314.4413624209708;1331.513997390952;8225.218604232603;9482.099200575944;9771.853422198672;9779.608815647383;9780.266480013992;9497.52512374382;9605.855912161305;9615.951920240406;9626.229901607872;9846.163076138537;1872.2323847222642;2151.967720484194;2178.302943058222;2179.030331502482;2181.9345419637357;1905.295040834886;1931.6329897319465;1932.1981377728296;1933.4006457997996;2151.698762173297;2065.855390122692;2081.1155259014936;2082.531430428157;2086.760022597398;2087.5929193807215;2049.8565100442975;2062.6390381683223;2065.9173633054734;2079.854410191287;2085.4786303424144;1866.7106598477258;1930.1700268884374;2070.7204527388762;2088.718023066882;2089.226986015169;9281.58232879522;9572.808543829084;9615.615375384976;9621.615135394575;9857.35927164732;5364.088105022156;5543.057680194376;5547.861303467405;5553.583481238883;5554.472325129147;3114.2542009991757;3146.354997225586;3170.9048728538182;3233.822139782311;3267.8202698851555;1016.3596933931576;1037.9636712715019;1052.3790963331594;1059.057438879398;1059.4067322299536;14407.135571865616;14478.203698796668;14483.3482493287;14589.92705036468;14594.489192878304;4809.495003024702;5037.874053148701;5041.495850414929;5047.116754567936;5137.51193636604;12139.514419423123;12153.756924861453;12164.574239901562;12165.756684866254;12166.452509637087;5577.163425486128;5580.330360356702;5581.888362232733;5583.134614134816;5584.21820945068;4803.087413391444;4928.285398617252;4987.077390682735;5033.496650335011;5068.974655126774;3659.5059666945153;3733.1040550268285;3779.848806047782;3819.961800382016;3831.061389959422;1070.1064611049705;1074.4412905289205;1075.9031687148195;1084.7017070305571;1095.9397233152094;677.1873751498222;697.8569393274366;705.0939542687622;705.217208898133;730.3098571849657;5368.725676080278;5395.190721391823;5398.838034858961;5415.296011518478;5593.412691667398;558.5252535345005;560.4922757781554;564.2692712936757;572.4990633195954;573.0372973404637;3501.2122272488996;3506.2987402519598;3540.8583656653445;3545.166885781839;3634.83643236055;12433.12968092225;13010.674733131747;13037.674058148623;13038.348082595832;13040;12805.679858003625;12936.094473386773;12946.223226606433;12946.611601651966;12947.482100716054;9154.633814647472;9242.584083716249;9366.812663746687;9373.765655858659;9382.0617938207;5502.642328248651;5529.087700529368;5532.612717109763;5606.915896261477;5709.429057094258;11299.491522881655;11309.943450282695;11369.715757106138;11514.36670983104;11539.076873849905;11398.08815294791;11920.344381059125;11947.820782688093;12023.519059237882;12156.513739450676;7629.084509858631;7680.615969201629;7686.500377475446;7689.577073261024;7701.229877012478;8899.955500222457;8941.955290223506;8988.775280618036;9041.141091596288;9071.319651026068;7496.437767167376;7676.232376762279;7689.42329325314;7720.3051725344985;7871.448998570045;7451.701931922662;7534.886976695464;7560.319571238615;7709.267619576131;7820.178881217618;6704.094947182068;6839.487038472234;6840.692168852314;6846.623435711083;6849.1780986281965;5106.846794596168;5178.818741344035;5227.869303267296;5382.085045542345;5421.430749771375;4952.083864485082;4995.125852975787;5072.898542029213;5094.1849304111165;5195.9740201300265 12:42:41 INFO - PID 1913 | |4;prototype.html;359.70863600483597;373.6935712715573;403.59438764042136;406.7142658260576;410.29634177385776;5094;5179.896402071961;5317.255584218213;5327.227552004957;5336.866578335539;5049.772760225789;5164.225366195073;5197.116490196666;5209.557187639055;5217.634765566402;4260.297050986584;4263.168682106991;4263.850765223217;4272.46594175728;4275.230458517465;4061.1877624475073;4137;4163.479565054368;4264.147170565884;4271.850485233017;4234.894127646818;4249.383839343293;4254.936175957363;4260.147970405916;4270.615644591986;4248.723832950848;4253.936190957138;4259.893502662443;4280.250956082704;4282.743035417885;3660.7254455915645;3758.9812050939845;3772.8868133955752;3818.198178382543;3852.114013776847;5093.592512599021;5109.156989096795;5110.134800797719;5119.462456442057;5126.820561280338;4885.60915126789;4888.804447822118;4897.779599917976;4899.485554016848;4901.632377571661;5340.652857564285;5536.532818803021;5552.139418390156;5558.305211848519;5582.525485333752;4197.538270790211;4227.309076369477;4236.6187043166265;4250.213710463574;4250.277452833026;5657.2928383952;5812.941870581325;5821.53427725781;5831.475167234969;5836.766529338822;4279.7218180818145;4315.331123675773;4346.326319420495;4360.585744354281;4368.584984426474;4165.437665915123;4264.978675106605;4354;4410.735355878657;4420.425344705168;4746.1931471649905;4889.997550502138;4897.624652043827;4904.2643603459765;4917.041176970528;5548.944510554924;5555.833325000176;5568.749406276727;5573.554115670737;5580.441955804468;1506.412499125342;1531.532882470857;1532.7470967290494;1537.1852917953502;1550.0622123615276;1090.8854570270166;1108.817045187535;1114.437209209344;1121.6354684727496;1125.921185517006;4822.517748225149;4827.324174615556;4827.637927155477;4833.589144922721;4858.125537403302;5742.13867919807;5759.280089988752;5764.855878603068;5773.913391299134;5779.537636989031;4629.305604159403;4807.038592281488;4828.637852161102;4842.709437433695;4856.368672072678;3021.6676165621766;3025.487256371814;3025.7458193645875;3048.1465189746905;3057.4955132403347;5371.543418809445;5375.543078838265;5377.78488860449;5380.166074258496;5390.622656414013;5655.4910058094965;5879.470847623735;5893.764249430061;5911.581220507047;5933.9406605934255;5349.893002139936;5361.437049109787;5369;5370.70461124634;5405.135178371442;5662;5739.684317362585;5776.768929242783;5780.104083867007;5828.134996800983;5636.097409833857;5673.177389278614;5675.716214189357;5682.119271512922;5703.287089113861;5376.73116344189;5388.488093631099;5395.81114660985;5399.217113518596;5401.243825864303;3998.8600398985895;4008.318585840472;4020.3969404589548;4047.494063242095;4057.107436363995;5474.288342515448;5606.159076138611;5606.971965140148;5785.855353616025;5792.9710351447975;5588.245586845724;5598.916016259841;5600.439956004367;5600.887982240414;5601.271834661468;5469.507744303032;5529.170624406372;5585.469380408855;5598.608097433141;5638.605297629127;5474.753636086306;5501.202325662676;5505.697186654773;5507.449255074461;5527.2538207341495;5632.183333416713;5640.605157638925;5658.434156584309;5659.151127330933;5677.375488696158;5324.893502130013;5370.62405631602;5395.622306438511;5430.51125398716;5476.91784623239 12:42:41 INFO - PID 1913 | |5;yui.html;692.491019100961;695.3012222716169;701.743863489826;703.961282129483;705.1679018757864;12964.157329773558;13888.583342499716;13935.23356215409;13939;13960.232187229698;13127.606171814838;13880.236586987712;14578.125312481232;14612.926935365309;14648.34082466289;829.6142293833366;833.4207725630689;854.3080105114846;859.6131740716671;859.7463748194298;845.218173189797;861.4917198852676;862.6290695001147;863.2619110660389;864.312871267344;901.625825282507;904.5160838951163;905.4974489158515;905.868649045888;908.27791905435;901.1844280925773;903.029243563169;903.20969151992;903.6159632156307;903.8282726282051;665.5008743442418;666.5234357434409;666.5833853841349;666.9233038200572;667.1460530520941;13221;13577.52478663242;14419.279036048156;14544.709105817825;14549.490767823076;12239.143259971806;12637;12642;13116.249101677638;13461.528846490326;9752.414855108716;10011.998800120002;10076.093151616316;10379.42913139777;10762.031417172413;2376.39401952501;2462.913798017061;2492.800575953919;2511.196417146514;2546.847189168656;44100.55899440996;44211.11577768427;44880.55119448829;51315.48684513145;55753.72123139358;10335.234524569343;10341.844872326921;10536.894631053592;10661.786764264827;10752.08607268391;20705.89647051755;21341.359759207247;21343.573128537344;21372.145114195257;21372.251971180933;6345.936540634627;6358.618482891041;6520.119783829123;6568.376004279586;6629.502787290972;3209.5506629071947;3270.869165233364;3292.111129994888;3351.497275408708;3441.862325507002;3630.2557975614936;3679.1170119171743;3705.5923848376656;3718.163413232055;3725.4970578971493;630.0832289019465;632.2886752403546;636.1570918532963;641.2401304454237;646.0954663471084;504.2260130699359;512.6539585779584;523.9161734122522;534.7833678381635;536.9838904832861;8283.213094755989;8308.584570771438;8383.664653413794;8537.74386768398;8634;515.646781954355;523.3013926408212;547.2448021729987;553.0653196098581;556.3879732294524;3778.414345776409;3950.249452603996;3991.401289806552;4024.63778259958;4122.464079669624;53679.731601341744;53742.73128634332;54571.18143227855;57088.85822283531;57138.14292785611;43946.78026609847;45936.770316148206;48496.5150348492;49697.25454118191;50260.99478010419;43039.13921721611;43695.781521092824;44798.328025079;44859.999999999345;44926.77536612296;8763.649454022023;9292.907070929205;9320.254379649476;9394.389364691273;9432.764180895532;24559.263222103367;24962.62556061624;24967.50064998726;25430.237092887244;26018.73981260163;29205.41589168247;29236.56145157867;29598.408031839674;29636.259093522833;29683.851580742823;11517.481713323088;11689.473973671375;11738.295702257892;11830.940845296063;11918.344491053076;12184.634460966185;12208.267503949792;12406.62780116598;12520.436580354088;12540.623781286577;11567.248128871597;11821.468033938321;12188.20776649515;12398.690032749253;12459.190152640049;11118.388488633203;11308.886911131112;11620.5351785931;11621.186516943733;11626.651200463999;10797.17757120491;11361.204715670154;11480.540778369106;11735.530578777094;11775.23460975034;8450.788730281547;8489.53307568065;8518.82962340762;8581.534065363174;8821.382503224713;9367.250619950524;9870.605175792887;9900.356476828983;9955.352902061342;10043.849342259726 12:42:41 INFO - PID 1913 | __end_tp_report 12:42:41 INFO - PID 1913 | __start_cc_report 12:42:41 INFO - PID 1913 | _x_x_mozilla_cycle_collect,1844 12:42:41 INFO - PID 1913 | __end_cc_report 12:42:41 INFO - PID 1913 | __startTimestamp1490557361034__endTimestamp 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | ------- Summary: start ------- 12:42:41 INFO - PID 1913 | Number of tests: 6 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | [#0] dojo.html Cycles:165 Average:5167.51 Median:5325.79 stddev:590.51 (11.1%) stddev-sans-first:559.70 12:42:41 INFO - PID 1913 | Values: 2700.4 2700.6 2739.0 2746.0 2753.9 5068.6 5148.8 5221.1 5236.4 5303.3 5234.7 5238.3 5275.3 5284.6 5296.6 4718.7 4757.6 4780.3 4832.4 4890.9 4770.1 4778.5 4798.9 4838.6 4896.4 4679.7 4701.8 4708.5 4720.7 4723.3 4728.2 4728.5 4729.1 4730.3 4733.1 4670.0 4681.2 4702.4 4702.8 4708.3 5169.8 5187.9 5253.2 5258.3 5260.9 5617.0 5617.0 5617.4 5621.8 5623.6 5537.7 5603.6 5669.0 5676.7 5716.4 5205.4 5220.7 5261.1 5304.5 5306.0 5484.6 5857.0 5859.2 5860.9 5867.4 5482.9 5497.1 5587.4 5628.2 5633.3 5650.2 5652.5 5655.5 5658.3 5659.5 5536.0 5547.3 5554.5 5603.4 5604.7 5454.7 5509.3 5512.4 5540.1 5542.4 4619.4 4636.2 4653.2 4669.2 4670.5 4012.7 4022.1 4025.4 4065.3 4070.7 4808.6 4811.5 4818.3 4818.8 4860.7 5537.2 5537.4 5593.4 5601.7 5637.2 4827.1 4828.5 4831.2 4843.4 4874.7 5061.5 5061.9 5064.7 5065.3 5066.8 5180.2 5232.8 5312.7 5325.8 5381.5 5591.0 5625.4 5662.1 5670.8 5694.0 5306.5 5323.3 5324.7 5324.8 5326.4 5553.9 5612.1 5615.3 5619.3 5667.2 5502.0 5539.3 5539.8 5540.3 5545.1 5400.8 5412.8 5439.7 5453.2 5471.2 5508.3 5517.9 5525.7 5526.7 5566.0 5430.0 5441.7 5446.4 5449.0 5456.0 5414.1 5463.9 5484.6 5547.7 5582.6 5417.0 5441.3 5442.7 5478.6 5485.9 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | [#1] ext.html Cycles:180 Average:32309.70 Median:29076.99 stddev:48612.21 (167.2%) stddev-sans-first:48690.32 12:42:41 INFO - PID 1913 | Values: 613.3 698.2 715.1 1070.1 1085.1 24885.8 25517.7 25523.7 25524.6 25534.0 30576.4 31097.8 31104.2 31107.1 31121.5 7181.5 7354.0 7360.5 7528.2 7530.1 5605.0 5677.5 7424.0 7551.8 7561.1 7120.6 7273.1 7275.0 7298.2 7346.9 7320.9 7350.9 7379.9 7396.3 7419.4 5557.3 5666.9 7336.4 7434.8 7450.3 28612.3 29040.1 29113.9 29203.1 31309.1 2642.8 2645.8 2647.9 2652.3 2682.4 23385.3 23604.4 23664.4 23738.0 23920.9 2380.9 2389.5 2404.3 2416.3 2423.3 150089.2 150661.0 151391.2 152091.0 153038.0 41793.2 41806.8 41856.0 41877.4 41905.8 43227.0 43264.1 43270.4 43345.6 43383.3 12723.7 12975.9 13005.5 13015.8 13018.4 10409.3 10410.2 10482.5 10490.5 10498.7 9704.7 9957.4 10715.9 11267.6 11547.8 4678.4 4682.6 4740.8 4765.1 4866.6 868.3 887.3 890.6 910.4 921.3 19115.3 19934.9 19977.2 20056.2 20201.3 970.6 976.8 977.6 982.8 992.3 10273.7 10374.9 10633.5 10680.6 10749.1 198272.0 199237.0 199777.0 200023.0 200224.0 40913.0 40963.6 40986.6 41059.0 41087.8 1176.1 1234.2 1246.7 1252.0 1264.1 18251.9 18429.2 19234.3 19577.7 19618.1 29239.0 29248.7 29434.3 29483.0 29565.7 204628.0 205202.0 205649.0 206433.0 206791.0 27014.3 27129.7 27199.7 27369.9 27396.6 22862.9 30177.4 30589.4 30768.0 34433.0 23814.9 26609.2 27192.0 27566.9 28278.9 28864.4 28899.6 28990.3 29305.0 30016.2 34608.0 34628.1 35288.1 35730.5 35732.5 35607.6 35700.3 35914.3 36083.4 36841.8 23386.3 24550.4 24793.5 24911.4 25207.2 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | [#2] jquery.html Cycles:180 Average:27525.59 Median:415.86 stddev:76707.54 (18445.5%) stddev-sans-first:76895.71 12:42:41 INFO - PID 1913 | Values: 411.6 413.0 413.6 418.1 437.8 16347.2 17201.7 17777.7 17887.9 17895.2 17621.1 17875.1 17950.0 18645.3 18817.1 5513.4 5571.3 5588.5 5612.8 5775.8 5585.1 5686.4 5696.1 5696.7 5700.0 5357.4 5505.6 5539.8 5658.1 5667.6 5488.4 5496.4 5518.9 5527.4 5528.6 4888.8 4981.8 4984.4 4992.7 5004.1 21066.9 21335.5 21885.2 22277.6 22332.3 1594.4 1605.3 1615.9 1622.6 1646.8 13607.2 14218.6 14824.3 14995.9 15325.1 1702.7 1741.5 1765.7 1791.6 1821.6 78915.6 82328.6 83927.6 85760.6 85856.0 10274.4 10561.9 10596.5 10703.3 10724.0 14296.9 14362.5 14364.1 14400.7 14423.3 7083.6 7236.7 7260.3 7471.4 7482.1 6717.5 6743.9 6915.6 6928.7 6966.8 6657.7 6767.0 6804.4 6832.0 6920.6 1922.5 1928.0 1934.0 1951.3 2095.2 829.2 847.9 860.3 868.3 873.1 16720.4 17101.7 17566.3 17723.0 18027.3 871.6 878.6 888.2 891.0 897.1 9145.4 9425.6 9693.0 9728.3 9876.2 435942.0 465836.0 469363.0 471252.0 480901.0 66926.3 84017.6 85632.6 92456.0 92731.0 1059.1 1106.4 1110.8 1117.0 1120.0 16509.3 16567.6 16950.8 17483.8 18227.1 40176.6 40653.8 40933.8 40959.4 42011.6 45866.0 46478.5 53555.0 54384.2 54748.7 7396.8 7651.8 7723.5 7940.5 8155.6 12103.8 12175.3 12352.9 12402.2 12451.6 10304.9 10588.6 10651.5 10654.4 10880.3 10050.8 10148.3 10169.1 10288.2 10370.5 10408.2 10498.8 10541.8 10677.0 10708.9 7780.8 7798.6 7923.8 7942.2 7988.4 5460.2 10070.6 10251.6 10342.6 10392.9 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | [#3] mootools.html Cycles:180 Average:6120.14 Median:5014.31 stddev:3978.96 (79.4%) stddev-sans-first:3972.28 12:42:41 INFO - PID 1913 | Values: 1105.3 1248.5 1312.0 1314.4 1331.5 8225.2 9482.1 9771.9 9779.6 9780.3 9497.5 9605.9 9616.0 9626.2 9846.2 1872.2 2152.0 2178.3 2179.0 2181.9 1905.3 1931.6 1932.2 1933.4 2151.7 2065.9 2081.1 2082.5 2086.8 2087.6 2049.9 2062.6 2065.9 2079.9 2085.5 1866.7 1930.2 2070.7 2088.7 2089.2 9281.6 9572.8 9615.6 9621.6 9857.4 5364.1 5543.1 5547.9 5553.6 5554.5 3114.3 3146.4 3170.9 3233.8 3267.8 1016.4 1038.0 1052.4 1059.1 1059.4 14407.1 14478.2 14483.3 14589.9 14594.5 4809.5 5037.9 5041.5 5047.1 5137.5 12139.5 12153.8 12164.6 12165.8 12166.5 5577.2 5580.3 5581.9 5583.1 5584.2 4803.1 4928.3 4987.1 5033.5 5069.0 3659.5 3733.1 3779.8 3820.0 3831.1 1070.1 1074.4 1075.9 1084.7 1095.9 677.2 697.9 705.1 705.2 730.3 5368.7 5395.2 5398.8 5415.3 5593.4 558.5 560.5 564.3 572.5 573.0 3501.2 3506.3 3540.9 3545.2 3634.8 12433.1 13010.7 13037.7 13038.3 13040.0 12805.7 12936.1 12946.2 12946.6 12947.5 9154.6 9242.6 9366.8 9373.8 9382.1 5502.6 5529.1 5532.6 5606.9 5709.4 11299.5 11309.9 11369.7 11514.4 11539.1 11398.1 11920.3 11947.8 12023.5 12156.5 7629.1 7680.6 7686.5 7689.6 7701.2 8900.0 8942.0 8988.8 9041.1 9071.3 7496.4 7676.2 7689.4 7720.3 7871.4 7451.7 7534.9 7560.3 7709.3 7820.2 6704.1 6839.5 6840.7 6846.6 6849.2 5106.8 5178.8 5227.9 5382.1 5421.4 4952.1 4995.1 5072.9 5094.2 5196.0 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | [#4] prototype.html Cycles:180 Average:4669.00 Median:5123.14 stddev:1303.03 (25.4%) stddev-sans-first:1265.91 12:42:41 INFO - PID 1913 | Values: 359.7 373.7 403.6 406.7 410.3 5094.0 5179.9 5317.3 5327.2 5336.9 5049.8 5164.2 5197.1 5209.6 5217.6 4260.3 4263.2 4263.9 4272.5 4275.2 4061.2 4137.0 4163.5 4264.1 4271.9 4234.9 4249.4 4254.9 4260.1 4270.6 4248.7 4253.9 4259.9 4280.3 4282.7 3660.7 3759.0 3772.9 3818.2 3852.1 5093.6 5109.2 5110.1 5119.5 5126.8 4885.6 4888.8 4897.8 4899.5 4901.6 5340.7 5536.5 5552.1 5558.3 5582.5 4197.5 4227.3 4236.6 4250.2 4250.3 5657.3 5812.9 5821.5 5831.5 5836.8 4279.7 4315.3 4346.3 4360.6 4368.6 4165.4 4265.0 4354.0 4410.7 4420.4 4746.2 4890.0 4897.6 4904.3 4917.0 5548.9 5555.8 5568.7 5573.6 5580.4 1506.4 1531.5 1532.7 1537.2 1550.1 1090.9 1108.8 1114.4 1121.6 1125.9 4822.5 4827.3 4827.6 4833.6 4858.1 5742.1 5759.3 5764.9 5773.9 5779.5 4629.3 4807.0 4828.6 4842.7 4856.4 3021.7 3025.5 3025.7 3048.1 3057.5 5371.5 5375.5 5377.8 5380.2 5390.6 5655.5 5879.5 5893.8 5911.6 5933.9 5349.9 5361.4 5369.0 5370.7 5405.1 5662.0 5739.7 5776.8 5780.1 5828.1 5636.1 5673.2 5675.7 5682.1 5703.3 5376.7 5388.5 5395.8 5399.2 5401.2 3998.9 4008.3 4020.4 4047.5 4057.1 5474.3 5606.2 5607.0 5785.9 5793.0 5588.2 5598.9 5600.4 5600.9 5601.3 5469.5 5529.2 5585.5 5598.6 5638.6 5474.8 5501.2 5505.7 5507.4 5527.3 5632.2 5640.6 5658.4 5659.2 5677.4 5324.9 5370.6 5395.6 5430.5 5476.9 12:42:41 INFO - PID 1913 | 12:42:41 INFO - PID 1913 | [#5] yui.html Cycles:180 Average:13008.43 Median:43367.46 stddev:14543.60 (33.5%) stddev-sans-first:14554.99 12:42:41 INFO - PID 1913 | Values: 692.5 695.3 701.7 704.0 705.2 12964.2 13888.6 13935.2 13939.0 13960.2 13127.6 13880.2 14578.1 14612.9 14648.3 829.6 833.4 854.3 859.6 859.7 845.2 861.5 862.6 863.3 864.3 901.6 904.5 905.5 905.9 908.3 901.2 903.0 903.2 903.6 903.8 665.5 666.5 666.6 666.9 667.1 13221.0 13577.5 14419.3 14544.7 14549.5 12239.1 12637.0 12642.0 13116.2 13461.5 9752.4 10012.0 10076.1 10379.4 10762.0 2376.4 2462.9 2492.8 2511.2 2546.8 44100.6 44211.1 44880.6 51315.5 55753.7 10335.2 10341.8 10536.9 10661.8 10752.1 20705.9 21341.4 21343.6 21372.1 21372.3 6345.9 6358.6 6520.1 6568.4 6629.5 3209.6 3270.9 3292.1 3351.5 3441.9 3630.3 3679.1 3705.6 3718.2 3725.5 630.1 632.3 636.2 641.2 646.1 504.2 512.7 523.9 534.8 537.0 8283.2 8308.6 8383.7 8537.7 8634.0 515.6 523.3 547.2 553.1 556.4 3778.4 3950.2 3991.4 4024.6 4122.5 53679.7 53742.7 54571.2 57088.9 57138.1 43946.8 45936.8 48496.5 49697.3 50261.0 43039.1 43695.8 44798.3 44860.0 44926.8 8763.6 9292.9 9320.3 9394.4 9432.8 24559.3 24962.6 24967.5 25430.2 26018.7 29205.4 29236.6 29598.4 29636.3 29683.9 11517.5 11689.5 11738.3 11830.9 11918.3 12184.6 12208.3 12406.6 12520.4 12540.6 11567.2 11821.5 12188.2 12398.7 12459.2 11118.4 11308.9 11620.5 11621.2 11626.7 10797.2 11361.2 11480.5 11735.5 11775.2 8450.8 8489.5 8518.8 8581.5 8821.4 9367.3 9870.6 9900.4 9955.4 10043.8 12:42:41 INFO - PID 1913 | -------- Summary: end -------- 12:42:41 INFO - PID 1913 | 12:42:41 INFO - TEST-INFO | 1913: exit 0 12:42:41 INFO - TEST-OK | dromaeo_css | took 1124659ms 12:42:41 INFO - TEST-START | kraken 12:42:41 INFO - operating with platform_type : linux_ 12:42:41 INFO - Initialising browser for kraken test... 12:42:41 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp38S_Go/profile http://localhost:53662/getInfo.html) 12:42:45 INFO - PID 2235 | __metrics Screen width/height:1600/1200 12:42:45 INFO - PID 2235 | colorDepth:24 12:42:45 INFO - PID 2235 | Browser inner width/height: 1024/697 12:42:45 INFO - PID 2235 | __metrics 12:42:46 ERROR - PID 2235 | 1490557366779 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) 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:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924: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 12:42:46 INFO - PID 2235 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:42:46 INFO - PID 2235 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:42:47 INFO - TEST-INFO | 2235: exit 0 12:42:47 INFO - Browser initialized. 12:42:47 INFO - Running cycle 1/1 for kraken test... 12:42:47 INFO - TEST-INFO | started process 2329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp38S_Go/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:00 INFO - PID 2329 | RSS: Main: 149782528 12:43:00 INFO - PID 2329 | 12:43:02 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53662/tests/kraken/driver.html?testName=audio-beat-detection) 12:43:03 INFO - PID 2329 | RSS: Main: 205418496 12:43:03 INFO - PID 2329 | 12:43:06 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53662/tests/kraken/driver.html?testName=audio-dft) 12:43:07 INFO - PID 2329 | RSS: Main: 185782272 12:43:07 INFO - PID 2329 | 12:43:10 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53662/tests/kraken/driver.html?testName=audio-fft) 12:43:10 INFO - PID 2329 | RSS: Main: 266047488 12:43:10 INFO - PID 2329 | 12:43:12 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53662/tests/kraken/driver.html?testName=audio-oscillator) 12:43:13 INFO - PID 2329 | RSS: Main: 178016256 12:43:13 INFO - PID 2329 | 12:43:15 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53662/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:43:16 INFO - PID 2329 | RSS: Main: 180666368 12:43:16 INFO - PID 2329 | 12:43:19 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53662/tests/kraken/driver.html?testName=imaging-darkroom) 12:43:20 INFO - PID 2329 | RSS: Main: 219303936 12:43:20 INFO - PID 2329 | 12:43:23 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53662/tests/kraken/driver.html?testName=imaging-desaturate) 12:43:24 INFO - PID 2329 | RSS: Main: 214781952 12:43:24 INFO - PID 2329 | 12:43:27 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53662/tests/kraken/driver.html?testName=json-parse-financial) 12:43:27 INFO - PID 2329 | RSS: Main: 216383488 12:43:27 INFO - PID 2329 | 12:43:29 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53662/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:30 INFO - PID 2329 | RSS: Main: 180162560 12:43:30 INFO - PID 2329 | 12:43:32 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:32 INFO - PID 2329 | RSS: Main: 187707392 12:43:32 INFO - PID 2329 | 12:43:34 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:35 INFO - PID 2329 | RSS: Main: 196919296 12:43:35 INFO - PID 2329 | 12:43:38 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:39 INFO - PID 2329 | RSS: Main: 205447168 12:43:39 INFO - PID 2329 | 12:43:42 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:42 INFO - PID 2329 | RSS: Main: 202420224 12:43:42 INFO - PID 2329 | 12:43:44 INFO - PID 2329 | Cycle 1(1): loaded http://localhost:53662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53662/tests/kraken/driver.html?testName=ai-astar) 12:43:45 INFO - PID 2329 | RSS: Main: 270589952 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | __start_tp_report 12:43:45 INFO - PID 2329 | _x_x_mozilla_page_load 12:43:45 INFO - PID 2329 | _x_x_mozilla_page_load_details 12:43:45 INFO - PID 2329 | |i|pagename|runs| 12:43:45 INFO - PID 2329 | |0;ai-astar;103;111;112;111;111;112;110;110;113;111 12:43:45 INFO - PID 2329 | |1;audio-beat-detection;149;129;123;156;123;128;123;122;153;136 12:43:45 INFO - PID 2329 | |2;audio-dft;164;161;162;162;163;162;162;162;162;162 12:43:45 INFO - PID 2329 | |3;audio-fft;75;74;75;111;77;75;76;76;92;75 12:43:45 INFO - PID 2329 | |4;audio-oscillator;84;85;117;84;84;85;101;85;85;109 12:43:45 INFO - PID 2329 | |5;imaging-gaussian-blur;113;113;113;114;113;114;113;114;113;113 12:43:45 INFO - PID 2329 | |6;imaging-darkroom;150;149;149;148;148;149;148;149;149;148 12:43:45 INFO - PID 2329 | |7;imaging-desaturate;94;94;95;95;94;93;94;94;94;94 12:43:45 INFO - PID 2329 | |8;json-parse-financial;67;67;66;67;66;69;76;66;67;67 12:43:45 INFO - PID 2329 | |9;json-stringify-tinderbox;55;55;54;54;55;55;55;55;55;54 12:43:45 INFO - PID 2329 | |10;stanford-crypto-aes;85;82;81;82;83;82;81;85;82;81 12:43:45 INFO - PID 2329 | |11;stanford-crypto-ccm;141;140;137;133;137;149;136;134;176;130 12:43:45 INFO - PID 2329 | |12;stanford-crypto-pbkdf2;199;192;194;194;205;200;211;195;196;194 12:43:45 INFO - PID 2329 | |13;stanford-crypto-sha256-iterative;73;74;69;71;70;72;69;69;77;69 12:43:45 INFO - PID 2329 | __end_tp_report 12:43:45 INFO - PID 2329 | __start_cc_report 12:43:45 INFO - PID 2329 | _x_x_mozilla_cycle_collect,2035 12:43:45 INFO - PID 2329 | __end_cc_report 12:43:45 INFO - PID 2329 | __startTimestamp1490557425007__endTimestamp 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | ------- Summary: start ------- 12:43:45 INFO - PID 2329 | Number of tests: 14 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.76 (2.5%) stddev-sans-first:0.97 12:43:45 INFO - PID 2329 | Values: 103.0 111.0 112.0 111.0 111.0 112.0 110.0 110.0 113.0 111.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:132.50 stddev:13.51 (10.2%) stddev-sans-first:13.22 12:43:45 INFO - PID 2329 | Values: 149.0 129.0 123.0 156.0 123.0 128.0 123.0 122.0 153.0 136.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:0.79 (0.5%) stddev-sans-first:0.50 12:43:45 INFO - PID 2329 | Values: 164.0 161.0 162.0 162.0 163.0 162.0 162.0 162.0 162.0 162.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.50 stddev:11.92 (15.8%) stddev-sans-first:12.47 12:43:45 INFO - PID 2329 | Values: 75.0 74.0 75.0 111.0 77.0 75.0 76.0 76.0 92.0 75.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:84.50 stddev:12.40 (14.7%) stddev-sans-first:12.81 12:43:45 INFO - PID 2329 | Values: 84.0 85.0 117.0 84.0 84.0 85.0 101.0 85.0 85.0 109.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 12:43:45 INFO - PID 2329 | Values: 113.0 113.0 113.0 114.0 113.0 114.0 113.0 114.0 113.0 113.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 12:43:45 INFO - PID 2329 | Values: 150.0 149.0 149.0 148.0 148.0 149.0 148.0 149.0 149.0 148.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:43:45 INFO - PID 2329 | Values: 94.0 94.0 95.0 95.0 94.0 93.0 94.0 94.0 94.0 94.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:67.00 stddev:3.01 (4.5%) stddev-sans-first:3.18 12:43:45 INFO - PID 2329 | Values: 67.0 67.0 66.0 67.0 66.0 69.0 76.0 66.0 67.0 67.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:55.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 12:43:45 INFO - PID 2329 | Values: 55.0 55.0 54.0 54.0 55.0 55.0 55.0 55.0 55.0 54.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.00 stddev:1.51 (1.8%) stddev-sans-first:1.27 12:43:45 INFO - PID 2329 | Values: 85.0 82.0 81.0 82.0 83.0 82.0 81.0 85.0 82.0 81.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:138.50 stddev:13.25 (9.6%) stddev-sans-first:14.05 12:43:45 INFO - PID 2329 | Values: 141.0 140.0 137.0 133.0 137.0 149.0 136.0 134.0 176.0 130.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.00 Median:197.50 stddev:5.96 (3.0%) stddev-sans-first:6.31 12:43:45 INFO - PID 2329 | Values: 199.0 192.0 194.0 194.0 205.0 200.0 211.0 195.0 196.0 194.0 12:43:45 INFO - PID 2329 | 12:43:45 INFO - PID 2329 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:71.50 stddev:2.71 (3.8%) stddev-sans-first:2.80 12:43:45 INFO - PID 2329 | Values: 73.0 74.0 69.0 71.0 70.0 72.0 69.0 69.0 77.0 69.0 12:43:45 INFO - PID 2329 | -------- Summary: end -------- 12:43:45 INFO - PID 2329 | 12:43:46 INFO - TEST-INFO | 2329: exit 0 12:43:46 INFO - TEST-OK | kraken | took 64353ms 12:43:46 INFO - SUITE-END | took 1189s 12:43:46 INFO - Completed test suite (00:19:49) 12:43:46 INFO - javascript benchmark 12:43:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6512.815113324223, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2700.3519155402705, 2700.5949107633865, 2739.041335532564, 2745.9725402745967, 2753.876075576599, 5068.5945124390055, 5148.794048238066, 5221.06020916235, 5236.423993360732, 5303.257543943846, 5234.73826308685, 5238.292830467895, 5275.287836142129, 5284.603654725892, 5296.6292359534755, 4718.693284936468, 4757.571818536331, 4780.259059845721, 4832.420109586837, 4890.902181956359, 4770.093682200406, 4778.546038126373, 4798.928016079762, 4838.612910967132, 4896.412430508344, 4679.742614156254, 4701.78841952113, 4708.4820669726305, 4720.740359280238, 4723.267893476483, 4728.172569800271, 4728.527147285244, 4729.101470720587, 4730.290456431529, 4733.0533893221145, 4669.976650116761, 4681.180793361148, 4702.36518070063, 4702.788374523155, 4708.317293992352, 5169.793208271664, 5187.896242075175, 5253.238280449352, 5258.342707161605, 5260.894782104337, 5617.0, 5617.045102332572, 5617.4382561743905, 5621.7751289948355, 5623.634463759883, 5537.695426751527, 5603.635763675348, 5668.971655141698, 5676.744546495418, 5716.428357164292, 5205.427402985669, 5220.660657057279, 5261.13191323435, 5304.549113325334, 5305.973470132702, 5484.588655850827, 5856.970715146397, 5859.1797148399255, 5860.8534786629825, 5867.354590994987, 5482.890342193134, 5497.120460726344, 5587.441255874462, 5628.212050312959, 5633.324001119892, 5650.208970744099, 5652.547796176296, 5655.519280861173, 5658.292713410824, 5659.462351076641, 5536.0, 5547.306586676666, 5554.527865131428, 5603.411641777555, 5604.6917419541505, 5454.699991500505, 5509.256250406143, 5512.4211957744665, 5540.058190107691, 5542.418046105182, 4619.376384188159, 4636.188666983332, 4653.162430762428, 4669.229577119737, 4670.509596492319, 4012.6990475714438, 4022.0749227677798, 4025.4364388985564, 4065.3495440729935, 4070.7150499465347, 4808.639352048541, 4811.4707382187935, 4818.325434439182, 4818.807247710122, 4860.659753817199, 5537.224788529609, 5537.390887002426, 5593.356763972114, 5601.719914004283, 5637.154426836008, 4827.131116399082, 4828.517148285144, 4831.154547954165, 4843.39457567804, 4874.731889746098, 5061.544460998454, 5061.924071138936, 5064.696118232918, 5065.265536497261, 5066.822661206914, 5180.197069454241, 5232.816851410183, 5312.681239125664, 5325.786968521215, 5381.461853814587, 5590.972045139748, 5625.353084395347, 5662.094064949588, 5670.8015219467125, 5693.971530142323, 5306.52241298285, 5323.281357016908, 5324.707141107121, 5324.787008519771, 5326.36083669962, 5553.8611534710335, 5612.102063669793, 5615.32616086056, 5619.269494965628, 5667.20659107717, 5502.0, 5539.279893613804, 5539.750711217925, 5540.279763630702, 5545.057340252086, 5400.83797486076, 5412.78348866041, 5439.673619582838, 5453.209284653783, 5471.206675032018, 5508.3114610673665, 5517.917231241454, 5525.696086715269, 5526.696031718294, 5566.025945459609, 5429.995450841607, 5441.673499589879, 5446.400895901527, 5449.04641687711, 5456.045192091288, 5414.133738601805, 5463.863403414787, 5484.561235101102, 5547.667139971614, 5582.609217354746, 5416.972915135557, 5441.347038355423, 5442.673439593637, 5478.643888147258, 5485.917711234255], "value": 5125.224773763371, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.3284053960914, 698.1761521404742, 715.1346870286955, 1070.0636942675164, 1085.104788549447, 24885.75114248857, 25517.74482255177, 25523.744762552393, 25524.61713074303, 25534.0, 30576.3884722306, 31097.84451077747, 31104.222394440094, 31107.06678799634, 31121.533177002362, 7181.497295189339, 7353.963230183842, 7360.484766066377, 7528.209537998513, 7530.058742657168, 5605.0, 5677.545796336304, 7424.0, 7551.848963020736, 7561.054868141482, 7120.608366539838, 7273.054502914613, 7275.0, 7298.197198308182, 7346.889796653055, 7320.926790732105, 7350.852982940365, 7379.852402951965, 7396.297351751575, 7419.4435417343375, 5557.305336832896, 5666.943330566724, 7336.376408005326, 7434.814129646748, 7450.254974502506, 28612.284692882637, 29040.128796135938, 29113.854430727923, 29203.12390628263, 31309.060728177963, 2642.775364094054, 2645.8280211786173, 2647.9338016549546, 2652.3236574673333, 2682.383051898055, 23385.298441046794, 23604.409889752722, 23664.40838979022, 23738.0, 23920.88039559791, 2380.8928598213124, 2389.545986262622, 2404.302752201859, 2416.311351264881, 2423.2730180945823, 150089.24955375263, 150661.0, 151391.24304378408, 152091.0, 153038.0, 41793.164136717096, 41806.790966045584, 41856.0, 41877.371839422434, 41905.79047104745, 43227.0, 43264.134717305475, 43270.35094473585, 43345.56654433478, 43383.34924976128, 12723.745525089631, 12975.935120324526, 13005.479780808662, 13015.804762928563, 13018.414171362312, 10409.27135100551, 10410.167186625204, 10482.475876206312, 10490.475476226158, 10498.685039448828, 9704.660336888175, 9957.352772069793, 10715.892841071489, 11267.605633802777, 11547.826782598268, 4678.391809064868, 4682.648801339845, 4740.834070807505, 4765.142274390573, 4866.562009419169, 868.2649653591112, 887.3123329419751, 890.5947793753827, 910.4355299714088, 921.2767977137914, 19115.256096952715, 19934.90032549857, 19977.20091196336, 20056.197752089753, 20201.292954746514, 970.6117552978865, 976.8496598469878, 977.6480467031863, 982.8280050991048, 992.2607657295362, 10273.691789246335, 10374.948125259325, 10633.5214915329, 10680.572777088984, 10749.08632766208, 198272.0, 199237.0, 199777.0, 200023.0, 200224.0, 40913.0, 40963.59036409598, 40986.590134098275, 41058.9735256615, 41087.794561027, 1176.1061593189067, 1234.1916044990546, 1246.650937737435, 1251.9812202816966, 1264.1088032936757, 18251.908740456212, 18429.157084291463, 19234.326798561982, 19577.70633440471, 19618.117184726438, 29239.0, 29248.707512924597, 29434.264143396587, 29483.0, 29565.704342956295, 204628.0, 205202.0, 205649.0, 206433.0, 206791.0, 27014.324641883322, 27129.72870271351, 27199.72800271972, 27369.86315068412, 27396.58905116385, 22862.885685571466, 30177.396452071276, 30589.388212235186, 30768.0, 34433.0, 23814.880925595262, 26609.201723948314, 27192.0, 27566.862165689847, 28278.85860570684, 28864.42271154607, 28899.56650650284, 28990.275243118245, 29305.0, 30016.249593759458, 34608.0, 34628.134296642784, 35288.11779705528, 35730.46404303885, 35732.4640130393, 35607.643923560434, 35700.28599427945, 35914.28171436609, 36083.37624806943, 36841.815790920875, 23386.298411047697, 24550.386240344134, 24793.504129917663, 24911.377215569755, 25207.24378268655], "value": 14323.95586780731, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.63775877228016, 412.9793510324483, 413.6236025217052, 418.098996662193, 437.78548511229485, 16347.182640867968, 17201.741973870387, 17777.733333999968, 17887.91056044718, 17895.19471623777, 17621.118944052778, 17875.10624468778, 17950.0, 18645.29643201738, 18817.059147042663, 5513.393526712059, 5571.331440227178, 5588.469095435948, 5612.831615051555, 5775.826725198251, 5585.050541407971, 5686.3744988051285, 5696.08862581987, 5696.6866822324755, 5700.030994730885, 5357.4106848246665, 5505.559555235592, 5539.750711218005, 5658.066419040853, 5667.5749318801045, 5488.423715509894, 5496.367917689477, 5518.91721624176, 5527.44725527446, 5528.55771538276, 4888.802243450342, 4981.750912454363, 4984.40187177541, 4992.6754760940785, 5004.074246264453, 21066.89466552673, 21335.466613334636, 21885.234016809336, 22277.55444891093, 22332.33003009912, 1594.4339759385482, 1605.2776250687236, 1615.9272832722556, 1622.6267958369524, 1646.8270831562752, 13607.183568985893, 14218.573442796733, 14824.3329050193, 14995.925020374938, 15325.080495170325, 1702.6935151672817, 1741.538492299529, 1765.7174852023743, 1791.6237590106093, 1821.5901422180036, 78915.6054219731, 82328.58835705843, 83927.58036209841, 85760.57119714424, 85856.0, 10274.434906080238, 10561.947190264153, 10596.50315395933, 10703.304285221435, 10724.0, 14296.928515357356, 14362.497312594009, 14364.138151710931, 14400.711985760227, 14423.278836058154, 7083.6458177090935, 7236.710531578783, 7260.346568808833, 7471.364933980563, 7482.1021477423055, 6717.5297729159465, 6743.932560674429, 6915.550489218184, 6928.688209030605, 6966.79099627012, 6657.700403481855, 6767.0, 6804.353586409283, 6831.965840170867, 6920.5847649140205, 1922.528980399811, 1927.9807201928081, 1933.9709904351446, 1951.317039036326, 2095.182878677317, 829.2371018662844, 847.9152084791472, 860.2515811244258, 868.2576397180446, 873.057098333798, 16720.41478548245, 17101.65796684084, 17566.297348105934, 17723.0, 18027.27890884376, 871.5729292646557, 878.6221924572495, 888.1695614600369, 891.0, 897.0580890065405, 9145.405548639317, 9425.575849086808, 9693.0, 9728.319017668837, 9876.160526355341, 435942.0, 465836.0, 469363.0, 471252.0, 480901.0, 66926.33073669298, 84017.57991210127, 85632.57183714042, 92456.0, 92731.0, 1059.0997651995897, 1106.435717783936, 1110.7556337605552, 1116.9888301116885, 1119.9832002519968, 16509.33962641481, 16567.591754700985, 16950.830491694924, 17483.82516174873, 18227.088645567936, 40176.59823401846, 40653.796731016155, 40933.79533102315, 40959.38560921529, 42011.579884200764, 45866.0, 46478.53521464742, 53555.0, 54384.184237235684, 54748.72625637006, 7396.778096657109, 7651.770446886602, 7723.459357844897, 7940.483868548526, 8155.5922203888385, 12103.75792484163, 12175.33035683046, 12352.938235309126, 12402.193857399601, 12451.626451206115, 10304.896951030394, 10588.576456941637, 10651.496898229065, 10654.360738355723, 10880.347179169275, 10050.798984020132, 10148.340357876714, 10169.084782369622, 10288.228382871464, 10370.481475926324, 10408.167346612101, 10498.790024199321, 10541.78916421652, 10676.985686360094, 10708.946455267675, 7780.766577002699, 7798.571078590732, 7923.841523169389, 7942.205779422012, 7988.400869934662, 5460.180972854104, 10070.597176112873, 10251.58993640246, 10342.586296548054, 10392.896071039193], "value": 8738.57643696861, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1105.2926127278538, 1248.5130798988394, 1312.0, 1314.4413624209708, 1331.513997390952, 8225.218604232603, 9482.099200575944, 9771.853422198672, 9779.608815647383, 9780.266480013992, 9497.52512374382, 9605.855912161305, 9615.951920240406, 9626.229901607872, 9846.163076138537, 1872.2323847222642, 2151.967720484194, 2178.302943058222, 2179.030331502482, 2181.9345419637357, 1905.295040834886, 1931.6329897319465, 1932.1981377728296, 1933.4006457997996, 2151.698762173297, 2065.855390122692, 2081.1155259014936, 2082.531430428157, 2086.760022597398, 2087.5929193807215, 2049.8565100442975, 2062.6390381683223, 2065.9173633054734, 2079.854410191287, 2085.4786303424144, 1866.7106598477258, 1930.1700268884374, 2070.7204527388762, 2088.718023066882, 2089.226986015169, 9281.58232879522, 9572.808543829084, 9615.615375384976, 9621.615135394575, 9857.35927164732, 5364.088105022156, 5543.057680194376, 5547.861303467405, 5553.583481238883, 5554.472325129147, 3114.2542009991757, 3146.354997225586, 3170.9048728538182, 3233.822139782311, 3267.8202698851555, 1016.3596933931576, 1037.9636712715019, 1052.3790963331594, 1059.057438879398, 1059.4067322299536, 14407.135571865616, 14478.203698796668, 14483.3482493287, 14589.92705036468, 14594.489192878304, 4809.495003024702, 5037.874053148701, 5041.495850414929, 5047.116754567936, 5137.51193636604, 12139.514419423123, 12153.756924861453, 12164.574239901562, 12165.756684866254, 12166.452509637087, 5577.163425486128, 5580.330360356702, 5581.888362232733, 5583.134614134816, 5584.21820945068, 4803.087413391444, 4928.285398617252, 4987.077390682735, 5033.496650335011, 5068.974655126774, 3659.5059666945153, 3733.1040550268285, 3779.848806047782, 3819.961800382016, 3831.061389959422, 1070.1064611049705, 1074.4412905289205, 1075.9031687148195, 1084.7017070305571, 1095.9397233152094, 677.1873751498222, 697.8569393274366, 705.0939542687622, 705.217208898133, 730.3098571849657, 5368.725676080278, 5395.190721391823, 5398.838034858961, 5415.296011518478, 5593.412691667398, 558.5252535345005, 560.4922757781554, 564.2692712936757, 572.4990633195954, 573.0372973404637, 3501.2122272488996, 3506.2987402519598, 3540.8583656653445, 3545.166885781839, 3634.83643236055, 12433.12968092225, 13010.674733131747, 13037.674058148623, 13038.348082595832, 13040.0, 12805.679858003625, 12936.094473386773, 12946.223226606433, 12946.611601651966, 12947.482100716054, 9154.633814647472, 9242.584083716249, 9366.812663746687, 9373.765655858659, 9382.0617938207, 5502.642328248651, 5529.087700529368, 5532.612717109763, 5606.915896261477, 5709.429057094258, 11299.491522881655, 11309.943450282695, 11369.715757106138, 11514.36670983104, 11539.076873849905, 11398.08815294791, 11920.344381059125, 11947.820782688093, 12023.519059237882, 12156.513739450676, 7629.084509858631, 7680.615969201629, 7686.500377475446, 7689.577073261024, 7701.229877012478, 8899.955500222457, 8941.955290223506, 8988.775280618036, 9041.141091596288, 9071.319651026068, 7496.437767167376, 7676.232376762279, 7689.42329325314, 7720.3051725344985, 7871.448998570045, 7451.701931922662, 7534.886976695464, 7560.319571238615, 7709.267619576131, 7820.178881217618, 6704.094947182068, 6839.487038472234, 6840.692168852314, 6846.623435711083, 6849.1780986281965, 5106.846794596168, 5178.818741344035, 5227.869303267296, 5382.085045542345, 5421.430749771375, 4952.083864485082, 4995.125852975787, 5072.898542029213, 5094.1849304111165, 5195.9740201300265], "value": 4547.804837112593, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.70863600483597, 373.6935712715573, 403.59438764042136, 406.7142658260576, 410.29634177385776, 5094.0, 5179.896402071961, 5317.255584218213, 5327.227552004957, 5336.866578335539, 5049.772760225789, 5164.225366195073, 5197.116490196666, 5209.557187639055, 5217.634765566402, 4260.297050986584, 4263.168682106991, 4263.850765223217, 4272.46594175728, 4275.230458517465, 4061.1877624475073, 4137.0, 4163.479565054368, 4264.147170565884, 4271.850485233017, 4234.894127646818, 4249.383839343293, 4254.936175957363, 4260.147970405916, 4270.615644591986, 4248.723832950848, 4253.936190957138, 4259.893502662443, 4280.250956082704, 4282.743035417885, 3660.7254455915645, 3758.9812050939845, 3772.8868133955752, 3818.198178382543, 3852.114013776847, 5093.592512599021, 5109.156989096795, 5110.134800797719, 5119.462456442057, 5126.820561280338, 4885.60915126789, 4888.804447822118, 4897.779599917976, 4899.485554016848, 4901.632377571661, 5340.652857564285, 5536.532818803021, 5552.139418390156, 5558.305211848519, 5582.525485333752, 4197.538270790211, 4227.309076369477, 4236.6187043166265, 4250.213710463574, 4250.277452833026, 5657.2928383952, 5812.941870581325, 5821.53427725781, 5831.475167234969, 5836.766529338822, 4279.7218180818145, 4315.331123675773, 4346.326319420495, 4360.585744354281, 4368.584984426474, 4165.437665915123, 4264.978675106605, 4354.0, 4410.735355878657, 4420.425344705168, 4746.1931471649905, 4889.997550502138, 4897.624652043827, 4904.2643603459765, 4917.041176970528, 5548.944510554924, 5555.833325000176, 5568.749406276727, 5573.554115670737, 5580.441955804468, 1506.412499125342, 1531.532882470857, 1532.7470967290494, 1537.1852917953502, 1550.0622123615276, 1090.8854570270166, 1108.817045187535, 1114.437209209344, 1121.6354684727496, 1125.921185517006, 4822.517748225149, 4827.324174615556, 4827.637927155477, 4833.589144922721, 4858.125537403302, 5742.13867919807, 5759.280089988752, 5764.855878603068, 5773.913391299134, 5779.537636989031, 4629.305604159403, 4807.038592281488, 4828.637852161102, 4842.709437433695, 4856.368672072678, 3021.6676165621766, 3025.487256371814, 3025.7458193645875, 3048.1465189746905, 3057.4955132403347, 5371.543418809445, 5375.543078838265, 5377.78488860449, 5380.166074258496, 5390.622656414013, 5655.4910058094965, 5879.470847623735, 5893.764249430061, 5911.581220507047, 5933.9406605934255, 5349.893002139936, 5361.437049109787, 5369.0, 5370.70461124634, 5405.135178371442, 5662.0, 5739.684317362585, 5776.768929242783, 5780.104083867007, 5828.134996800983, 5636.097409833857, 5673.177389278614, 5675.716214189357, 5682.119271512922, 5703.287089113861, 5376.73116344189, 5388.488093631099, 5395.81114660985, 5399.217113518596, 5401.243825864303, 3998.8600398985895, 4008.318585840472, 4020.3969404589548, 4047.494063242095, 4057.107436363995, 5474.288342515448, 5606.159076138611, 5606.971965140148, 5785.855353616025, 5792.9710351447975, 5588.245586845724, 5598.916016259841, 5600.439956004367, 5600.887982240414, 5601.271834661468, 5469.507744303032, 5529.170624406372, 5585.469380408855, 5598.608097433141, 5638.605297629127, 5474.753636086306, 5501.202325662676, 5505.697186654773, 5507.449255074461, 5527.2538207341495, 5632.183333416713, 5640.605157638925, 5658.434156584309, 5659.151127330933, 5677.375488696158, 5324.893502130013, 5370.62405631602, 5395.622306438511, 5430.51125398716, 5476.91784623239], "value": 4281.536308011977, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.491019100961, 695.3012222716169, 701.743863489826, 703.961282129483, 705.1679018757864, 12964.157329773558, 13888.583342499716, 13935.23356215409, 13939.0, 13960.232187229698, 13127.606171814838, 13880.236586987712, 14578.125312481232, 14612.926935365309, 14648.34082466289, 829.6142293833366, 833.4207725630689, 854.3080105114846, 859.6131740716671, 859.7463748194298, 845.218173189797, 861.4917198852676, 862.6290695001147, 863.2619110660389, 864.312871267344, 901.625825282507, 904.5160838951163, 905.4974489158515, 905.868649045888, 908.27791905435, 901.1844280925773, 903.029243563169, 903.20969151992, 903.6159632156307, 903.8282726282051, 665.5008743442418, 666.5234357434409, 666.5833853841349, 666.9233038200572, 667.1460530520941, 13221.0, 13577.52478663242, 14419.279036048156, 14544.709105817825, 14549.490767823076, 12239.143259971806, 12637.0, 12642.0, 13116.249101677638, 13461.528846490326, 9752.414855108716, 10011.998800120002, 10076.093151616316, 10379.42913139777, 10762.031417172413, 2376.39401952501, 2462.913798017061, 2492.800575953919, 2511.196417146514, 2546.847189168656, 44100.55899440996, 44211.11577768427, 44880.55119448829, 51315.48684513145, 55753.72123139358, 10335.234524569343, 10341.844872326921, 10536.894631053592, 10661.786764264827, 10752.08607268391, 20705.89647051755, 21341.359759207247, 21343.573128537344, 21372.145114195257, 21372.251971180933, 6345.936540634627, 6358.618482891041, 6520.119783829123, 6568.376004279586, 6629.502787290972, 3209.5506629071947, 3270.869165233364, 3292.111129994888, 3351.497275408708, 3441.862325507002, 3630.2557975614936, 3679.1170119171743, 3705.5923848376656, 3718.163413232055, 3725.4970578971493, 630.0832289019465, 632.2886752403546, 636.1570918532963, 641.2401304454237, 646.0954663471084, 504.2260130699359, 512.6539585779584, 523.9161734122522, 534.7833678381635, 536.9838904832861, 8283.213094755989, 8308.584570771438, 8383.664653413794, 8537.74386768398, 8634.0, 515.646781954355, 523.3013926408212, 547.2448021729987, 553.0653196098581, 556.3879732294524, 3778.414345776409, 3950.249452603996, 3991.401289806552, 4024.63778259958, 4122.464079669624, 53679.731601341744, 53742.73128634332, 54571.18143227855, 57088.85822283531, 57138.14292785611, 43946.78026609847, 45936.770316148206, 48496.5150348492, 49697.25454118191, 50260.99478010419, 43039.13921721611, 43695.781521092824, 44798.328025079, 44859.999999999345, 44926.77536612296, 8763.649454022023, 9292.907070929205, 9320.254379649476, 9394.389364691273, 9432.764180895532, 24559.263222103367, 24962.62556061624, 24967.50064998726, 25430.237092887244, 26018.73981260163, 29205.41589168247, 29236.56145157867, 29598.408031839674, 29636.259093522833, 29683.851580742823, 11517.481713323088, 11689.473973671375, 11738.295702257892, 11830.940845296063, 11918.344491053076, 12184.634460966185, 12208.267503949792, 12406.62780116598, 12520.436580354088, 12540.623781286577, 11567.248128871597, 11821.468033938321, 12188.20776649515, 12398.690032749253, 12459.190152640049, 11118.388488633203, 11308.886911131112, 11620.5351785931, 11621.186516943733, 11626.651200463999, 10797.17757120491, 11361.204715670154, 11480.540778369106, 11735.530578777094, 11775.23460975034, 8450.788730281547, 8489.53307568065, 8518.82962340762, 8581.534065363174, 8821.382503224713, 9367.250619950524, 9870.605175792887, 9900.356476828983, 9955.352902061342, 10043.849342259726], "value": 6108.882863580526, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 112.0, 111.0, 111.0, 112.0, 110.0, 110.0, 113.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 129.0, 123.0, 156.0, 123.0, 128.0, 123.0, 122.0, 153.0, 136.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 162.0, 163.0, 162.0, 162.0, 162.0, 162.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 111.0, 77.0, 75.0, 76.0, 76.0, 92.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 117.0, 84.0, 84.0, 85.0, 101.0, 85.0, 85.0, 109.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 148.0, 148.0, 149.0, 148.0, 149.0, 149.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 66.0, 69.0, 76.0, 66.0, 67.0, 67.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 81.0, 82.0, 83.0, 82.0, 81.0, 85.0, 82.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 137.0, 133.0, 137.0, 149.0, 136.0, 134.0, 176.0, 130.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 192.0, 194.0, 194.0, 205.0, 200.0, 211.0, 195.0, 196.0, 194.0], "value": 198.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 69.0, 71.0, 70.0, 72.0, 69.0, 69.0, 77.0, 69.0], "value": 71.3, "unit": "score"}]}]} 12:43:46 INFO - Return code: 0 12:43:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:43:46 ERROR - # TBPL SUCCESS # 12:43:46 INFO - Running post-action listener: _resource_record_post_action 12:43:46 INFO - [mozharness: 2017-03-26 19:43:46.829144Z] Finished run-tests step (success) 12:43:46 INFO - Running post-run listener: _resource_record_post_run 12:43:46 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 12288; Write bytes: 366923776; Read time: 280; Write time: 274208 12:43:46 INFO - TinderboxPrint: CPU usage
12.9% 12:43:46 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 280 12:43:46 INFO - TinderboxPrint: I/O write bytes / time
366,923,776 / 274,208 12:43:46 INFO - TinderboxPrint: CPU idle
8,373.1 (87.0%) 12:43:46 INFO - TinderboxPrint: CPU user
1,200.8 (12.5%) 12:43:46 INFO - TinderboxPrint: Swap in / out
0 / 0 12:43:46 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 106389504; Read time: 60; Write time: 109300 12:43:46 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 259997696; Read time: 220; Write time: 164908 12:43:47 INFO - Running post-run listener: _upload_blobber_files 12:43:47 INFO - Blob upload gear active. 12:43:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:43:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:43:47 INFO - (blobuploader) - INFO - Open directory for files ... 12:43:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:43:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:43:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:48 INFO - (blobuploader) - INFO - Done attempting. 12:43:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:43:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:43:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:49 INFO - (blobuploader) - INFO - Done attempting. 12:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:43:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:50 INFO - (blobuploader) - INFO - Done attempting. 12:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:53 INFO - (blobuploader) - INFO - Done attempting. 12:43:53 INFO - (blobuploader) - INFO - Iteration through files over. 12:43:53 INFO - Return code: 0 12:43:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:43:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:43:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a68f6eb52af80efdf9cb0683b6c53190a3f111c8bde6bdbe57586a8184b6fd4cc82049f143d0f7daf1de6812275abbc8d60cfa516b1259427e74937fa93f8682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e93afc4692c0ce5fa808c805b2886b53deaf1f59fa4884a403d113ab1b47dae2c912107a8b4a840a02a78c2fbde17f096267c1c0e8f889ab26028f608cc3a50f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dc2feb54a0b97b5a853bd5c1a1bc8be59ab46995fcf9da8d759d5b2077cbeb7924a37491222a881c0b6afee3771e002c6ef9720a4ef52b98f19d1ea3327b6a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/260575d048b2db74c094385d9b885d9cc8e9d570b52f4889d63aba40baa6293d5503e261c3bc920416f0125d001bbcef7542545efe11bf5d41734e7b9ded04d3"} 12:43:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:43:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:43:53 INFO - Contents: 12:43:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a68f6eb52af80efdf9cb0683b6c53190a3f111c8bde6bdbe57586a8184b6fd4cc82049f143d0f7daf1de6812275abbc8d60cfa516b1259427e74937fa93f8682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e93afc4692c0ce5fa808c805b2886b53deaf1f59fa4884a403d113ab1b47dae2c912107a8b4a840a02a78c2fbde17f096267c1c0e8f889ab26028f608cc3a50f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dc2feb54a0b97b5a853bd5c1a1bc8be59ab46995fcf9da8d759d5b2077cbeb7924a37491222a881c0b6afee3771e002c6ef9720a4ef52b98f19d1ea3327b6a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/260575d048b2db74c094385d9b885d9cc8e9d570b52f4889d63aba40baa6293d5503e261c3bc920416f0125d001bbcef7542545efe11bf5d41734e7b9ded04d3"} 12:43:53 INFO - Running post-run listener: copy_logs_to_upload_dir 12:43:53 INFO - Copying logs to upload dir... 12:43:53 INFO - mkdir: /builds/slave/test/build/upload/logs 12:43:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.840004 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-03-26 12:43:53.330841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 12:43:53.336725) ========= 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=17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a68f6eb52af80efdf9cb0683b6c53190a3f111c8bde6bdbe57586a8184b6fd4cc82049f143d0f7daf1de6812275abbc8d60cfa516b1259427e74937fa93f8682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e93afc4692c0ce5fa808c805b2886b53deaf1f59fa4884a403d113ab1b47dae2c912107a8b4a840a02a78c2fbde17f096267c1c0e8f889ab26028f608cc3a50f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dc2feb54a0b97b5a853bd5c1a1bc8be59ab46995fcf9da8d759d5b2077cbeb7924a37491222a881c0b6afee3771e002c6ef9720a4ef52b98f19d1ea3327b6a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/260575d048b2db74c094385d9b885d9cc8e9d570b52f4889d63aba40baa6293d5503e261c3bc920416f0125d001bbcef7542545efe11bf5d41734e7b9ded04d3"} build_url:https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011519 build_url: 'https://queue.taskcluster.net/v1/task/MPKDCmepT6ejwiWPeDlEyQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a68f6eb52af80efdf9cb0683b6c53190a3f111c8bde6bdbe57586a8184b6fd4cc82049f143d0f7daf1de6812275abbc8d60cfa516b1259427e74937fa93f8682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e93afc4692c0ce5fa808c805b2886b53deaf1f59fa4884a403d113ab1b47dae2c912107a8b4a840a02a78c2fbde17f096267c1c0e8f889ab26028f608cc3a50f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dc2feb54a0b97b5a853bd5c1a1bc8be59ab46995fcf9da8d759d5b2077cbeb7924a37491222a881c0b6afee3771e002c6ef9720a4ef52b98f19d1ea3327b6a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/260575d048b2db74c094385d9b885d9cc8e9d570b52f4889d63aba40baa6293d5503e261c3bc920416f0125d001bbcef7542545efe11bf5d41734e7b9ded04d3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 12:43:53.362314) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:43:53.362639) ========= 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=17051fcd5208b27b50bedfeb000001dd-1490555387.475375-1512656285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:43:53.448055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 12:43:53.448379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 12:43:53.448681) ========= ========= Total master_lag: 0.17 =========