builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-039 starttime: 1486137727.8 results: success (0) revision: c02020dd7e6e190b046e0bdc992a75b647c4cffa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.798334) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.798894) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.799192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.813374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.813795) ========= 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=5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-03 08:02:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 39.4M=0s 2017-02-03 08:02:08 (39.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.169712 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.995058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:07.995396) ========= 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=5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022312 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:08.045068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 08:02:08.045406) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c02020dd7e6e190b046e0bdc992a75b647c4cffa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c02020dd7e6e190b046e0bdc992a75b647c4cffa --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=5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-03 08:02:08,712 truncating revision to first 12 chars 2017-02-03 08:02:08,712 Setting DEBUG logging. 2017-02-03 08:02:08,712 attempt 1/10 2017-02-03 08:02:08,712 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c02020dd7e6e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-03 08:02:09,700 unpacking tar archive at: mozilla-aurora-c02020dd7e6e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.268080 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 08:02:09.326831) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:09.327194) ========= script_repo_revision: c02020dd7e6e190b046e0bdc992a75b647c4cffa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:09.327569) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:09.327837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 08:02:09.337202) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-03 08:02:09.337578) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:02:10 INFO - MultiFileLogger online at 20170203 08:02:10 in /builds/slave/test 08:02:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:02:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:02:10 INFO - {'append_to_log': False, 08:02:10 INFO - 'base_work_dir': '/builds/slave/test', 08:02:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:02:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:02:10 INFO - 'branch': 'Mozilla-Aurora', 08:02:10 INFO - 'buildbot_json_path': 'buildprops.json', 08:02:10 INFO - 'config_files': ('talos/linux_config.py',), 08:02:10 INFO - 'default_actions': ('clobber', 08:02:10 INFO - 'read-buildbot-config', 08:02:10 INFO - 'download-and-extract', 08:02:10 INFO - 'populate-webroot', 08:02:10 INFO - 'create-virtualenv', 08:02:10 INFO - 'install', 08:02:10 INFO - 'run-tests'), 08:02:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:02:10 INFO - 'download_minidump_stackwalk': True, 08:02:10 INFO - 'download_symbols': 'ondemand', 08:02:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:02:10 INFO - 'tooltool.py': '/tools/tooltool.py', 08:02:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:02:10 INFO - '/tools/misc-python/virtualenv.py')}, 08:02:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:02:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:02:10 INFO - 'installer_path': 'installer.exe', 08:02:10 INFO - 'log_level': 'info', 08:02:10 INFO - 'log_name': 'talos', 08:02:10 INFO - 'log_to_console': True, 08:02:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:02:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:02:10 INFO - 'opt_config_files': (), 08:02:10 INFO - 'pip_index': False, 08:02:10 INFO - 'sps_profile': False, 08:02:10 INFO - 'sps_profile_interval': 0, 08:02:10 INFO - 'suite': 'dromaeojs', 08:02:10 INFO - 'system_bits': '32', 08:02:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:02:10 INFO - 'title': 'talos-linux64-ix-039', 08:02:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:02:10 INFO - 'use_talos_json': True, 08:02:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:02:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:02:10 INFO - 'work_dir': 'build'} 08:02:10 INFO - [mozharness: 2017-02-03 16:02:10.102655Z] Running clobber step. 08:02:10 INFO - Running pre-action listener: _resource_record_pre_action 08:02:10 INFO - Running main action method: clobber 08:02:10 INFO - rmtree: /builds/slave/test/build 08:02:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:02:10 INFO - Running post-action listener: _resource_record_post_action 08:02:10 INFO - [mozharness: 2017-02-03 16:02:10.569557Z] Finished clobber step (success) 08:02:10 INFO - [mozharness: 2017-02-03 16:02:10.569659Z] Running read-buildbot-config step. 08:02:10 INFO - Running pre-action listener: _resource_record_pre_action 08:02:10 INFO - Running main action method: read_buildbot_config 08:02:10 INFO - Using buildbot properties: 08:02:10 INFO - { 08:02:10 INFO - "project": "", 08:02:10 INFO - "product": "firefox", 08:02:10 INFO - "who": "ryanvm@gmail.com", 08:02:10 INFO - "installer_path": "public/build/target.tar.bz2", 08:02:10 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:02:10 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:02:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:02:10 INFO - "stage_platform": "linux64-pgo", 08:02:10 INFO - "basedir": "/builds/slave/test", 08:02:10 INFO - "buildnumber": 764, 08:02:10 INFO - "platform": "ubuntu64_hw", 08:02:10 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:02:10 INFO - "slavebuilddir": "test", 08:02:10 INFO - "taskId": "HCHghs1gQZmhd-fqn95EvQ", 08:02:10 INFO - "branch": "mozilla-aurora", 08:02:10 INFO - "script_repo_revision": "production", 08:02:10 INFO - "revision": "c02020dd7e6e190b046e0bdc992a75b647c4cffa", 08:02:10 INFO - "slavename": "talos-linux64-ix-039", 08:02:10 INFO - "repo_path": "releases/mozilla-aurora" 08:02:10 INFO - } 08:02:10 INFO - Finding installer, test and symbols from parent task. 08:02:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HCHghs1gQZmhd-fqn95EvQ'}, attempt #1 08:02:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HCHghs1gQZmhd-fqn95EvQ'}, attempt #1 08:02:11 INFO - Task dependencies: M6xI86qjR1-WS9XCjj5_tQ 08:02:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ'}, attempt #1 08:02:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2 08:02:11 INFO - Set test_url: https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/test_packages.json 08:02:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.crashreporter-symbols.zip 08:02:11 INFO - Running post-action listener: _resource_record_post_action 08:02:11 INFO - [mozharness: 2017-02-03 16:02:11.977986Z] Finished read-buildbot-config step (success) 08:02:11 INFO - [mozharness: 2017-02-03 16:02:11.978203Z] Running download-and-extract step. 08:02:11 INFO - Running pre-action listener: _resource_record_pre_action 08:02:11 INFO - Running main action method: download_and_extract 08:02:11 INFO - mkdir: /builds/slave/test/build/tests 08:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:11 INFO - trying https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.test_packages.json 08:02:11 INFO - Downloading https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:02:12 INFO - Downloaded 896 bytes. 08:02:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:02:12 INFO - Using the following test package requirements: 08:02:12 INFO - {u'common': [u'target.common.tests.zip'], 08:02:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:02:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:02:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:02:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:02:12 INFO - u'mozbase': [u'target.common.tests.zip'], 08:02:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:02:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:02:12 INFO - u'web-platform': [u'target.common.tests.zip', 08:02:12 INFO - u'target.web-platform.tests.zip'], 08:02:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:02:12 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:02:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.common.tests.zip 08:02:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:02:12 INFO - Fetch https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.common.tests.zip into memory 08:02:13 INFO - Expected file size: 21856096 08:02:13 INFO - Obtained file size: 21856096 08:02:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:02:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.common.tests.zip 08:02:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:02:17 INFO - Fetch https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.common.tests.zip into memory 08:02:18 INFO - Expected file size: 21856096 08:02:18 INFO - Obtained file size: 21856096 08:02:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.talos.tests.zip 08:02:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:02:22 INFO - Fetch https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.talos.tests.zip into memory 08:02:23 INFO - Expected file size: 14403485 08:02:23 INFO - Obtained file size: 14403485 08:02:23 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')]} 08:02:23 INFO - trying https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2 08:02:23 INFO - Downloading https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:02:24 INFO - Downloaded 66088838 bytes. 08:02:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2 08:02:24 INFO - mkdir: /builds/slave/test/properties 08:02:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:24 INFO - Contents: 08:02:24 INFO - build_url:https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2 08:02:24 INFO - Running post-action listener: _resource_record_post_action 08:02:24 INFO - Running post-action listener: set_extra_try_arguments 08:02:24 INFO - [mozharness: 2017-02-03 16:02:24.990210Z] Finished download-and-extract step (success) 08:02:24 INFO - [mozharness: 2017-02-03 16:02:24.990307Z] Running populate-webroot step. 08:02:24 INFO - Running pre-action listener: _resource_record_pre_action 08:02:24 INFO - Running main action method: populate_webroot 08:02:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:24 INFO - '--tppagecycles', 08:02:24 INFO - '7'], 08:02:24 INFO - 'tests': ['tsvgm']}}, 08:02:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:02:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:02:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:02:24 INFO - 'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:02:24 INFO - 'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['damp', 'tps']}, 08:02:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:02:24 INFO - 'tests': ['damp', 'tps']}, 08:02:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['dromaeo_dom']}, 08:02:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['basic_compositor_video']}, 08:02:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:02:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:02:24 INFO - 'tests': ['a11yr', 08:02:24 INFO - 'ts_paint', 08:02:24 INFO - 'tpaint', 08:02:24 INFO - 'sessionrestore', 08:02:24 INFO - 'sessionrestore_no_auto_restore', 08:02:24 INFO - 'tabpaint']}, 08:02:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:02:24 INFO - 'ts_paint', 08:02:24 INFO - 'tpaint', 08:02:24 INFO - 'sessionrestore', 08:02:24 INFO - 'sessionrestore_no_auto_restore', 08:02:24 INFO - 'tabpaint']}, 08:02:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:02:24 INFO - 'ts_paint', 08:02:24 INFO - 'tpaint', 08:02:24 INFO - 'sessionrestore', 08:02:24 INFO - 'sessionrestore_no_auto_restore', 08:02:24 INFO - 'tabpaint']}, 08:02:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:02:24 INFO - 'ts_paint', 08:02:24 INFO - 'tpaint', 08:02:24 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['tsvgx', 08:02:25 INFO - 'tsvgr_opacity', 08:02:25 INFO - 'tart', 08:02:25 INFO - 'tscrollx', 08:02:25 INFO - 'cart', 08:02:25 INFO - 'tsvg_static']}, 08:02:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:02:25 INFO - 'tsvgr_opacity', 08:02:25 INFO - 'tart', 08:02:25 INFO - 'tscrollx', 08:02:25 INFO - 'cart', 08:02:25 INFO - 'tsvg_static']}, 08:02:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:02:25 INFO - 'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['tp5o']}, 08:02:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:02:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:02:25 INFO - 'talos_options': ['--disable-e10s', 08:02:25 INFO - '--xperf_path', 08:02:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:25 INFO - 'tests': ['tp5n']}, 08:02:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:02:25 INFO - 'talos_options': ['--xperf_path', 08:02:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:25 INFO - 'tests': ['tp5n']}}, 08:02:25 INFO - 'talos.zip': {'path': '', 08:02:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:25 INFO - Running post-action listener: _resource_record_post_action 08:02:25 INFO - [mozharness: 2017-02-03 16:02:25.003389Z] Finished populate-webroot step (success) 08:02:25 INFO - [mozharness: 2017-02-03 16:02:25.003479Z] Running create-virtualenv step. 08:02:25 INFO - Running pre-action listener: _resource_record_pre_action 08:02:25 INFO - Running main action method: create_virtualenv 08:02:25 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:25 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 08:02:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:25 INFO - Using real prefix '/usr' 08:02:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:27 INFO - Installing pip.................done. 08:02:27 INFO - Return code: 0 08:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:02:27 INFO - Reading from file tmpfile_stdout 08:02:27 INFO - Output received: 08:02:27 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) 08:02:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02:27 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')]} 08:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:27 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')]} 08:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:27 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:27 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 08:02:27 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 08:02:27 INFO - Using env: {'DISPLAY': ':0', 08:02:27 INFO - 'HOME': '/home/cltbld', 08:02:27 INFO - 'LANG': 'en_US.UTF-8', 08:02:27 INFO - 'LANGUAGE': 'en_US:en', 08:02:27 INFO - 'LOGNAME': 'cltbld', 08:02:27 INFO - 'MAIL': '/var/mail/cltbld', 08:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:27 INFO - 'MOZ_NO_REMOTE': '1', 08:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:27 INFO - 'NO_EM_RESTART': '1', 08:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:27 INFO - 'PWD': '/builds/slave/test', 08:02:27 INFO - 'SHELL': '/bin/bash', 08:02:27 INFO - 'SHLVL': '1', 08:02:27 INFO - 'TERM': 'linux', 08:02:27 INFO - 'TMOUT': '86400', 08:02:27 INFO - 'USER': 'cltbld', 08:02:27 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:27 INFO - '_': '/tools/buildbot/bin/python'} 08:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:28 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 08:02:28 INFO - Cleaning up... 08:02:28 INFO - Return code: 0 08:02:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:02:28 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')]} 08:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:28 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')]} 08:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:28 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:28 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 08:02:28 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 08:02:28 INFO - Using env: {'DISPLAY': ':0', 08:02:28 INFO - 'HOME': '/home/cltbld', 08:02:28 INFO - 'LANG': 'en_US.UTF-8', 08:02:28 INFO - 'LANGUAGE': 'en_US:en', 08:02:28 INFO - 'LOGNAME': 'cltbld', 08:02:28 INFO - 'MAIL': '/var/mail/cltbld', 08:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:28 INFO - 'MOZ_NO_REMOTE': '1', 08:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:28 INFO - 'NO_EM_RESTART': '1', 08:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:28 INFO - 'PWD': '/builds/slave/test', 08:02:28 INFO - 'SHELL': '/bin/bash', 08:02:28 INFO - 'SHLVL': '1', 08:02:28 INFO - 'TERM': 'linux', 08:02:28 INFO - 'TMOUT': '86400', 08:02:28 INFO - 'USER': 'cltbld', 08:02:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:28 INFO - '_': '/tools/buildbot/bin/python'} 08:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:28 INFO - Downloading/unpacking psutil>=3.1.1 08:02:28 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 08:02:28 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 08:02:28 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 08:02:28 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 08:02:28 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 08:02:28 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 08:02:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:30 INFO - Installing collected packages: psutil 08:02:30 INFO - Running setup.py install for psutil 08:02:30 INFO - building 'psutil._psutil_linux' extension 08:02:30 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 08:02:30 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 08:02:30 INFO - building 'psutil._psutil_posix' extension 08:02:30 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 08:02:30 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 08:02:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:30 INFO - Successfully installed psutil 08:02:30 INFO - Cleaning up... 08:02:30 INFO - Return code: 0 08:02:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:30 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:30 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 08:02:30 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 08:02:30 INFO - Using env: {'DISPLAY': ':0', 08:02:30 INFO - 'HOME': '/home/cltbld', 08:02:30 INFO - 'LANG': 'en_US.UTF-8', 08:02:30 INFO - 'LANGUAGE': 'en_US:en', 08:02:30 INFO - 'LOGNAME': 'cltbld', 08:02:30 INFO - 'MAIL': '/var/mail/cltbld', 08:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:30 INFO - 'MOZ_NO_REMOTE': '1', 08:02:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:30 INFO - 'NO_EM_RESTART': '1', 08:02:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:30 INFO - 'PWD': '/builds/slave/test', 08:02:30 INFO - 'SHELL': '/bin/bash', 08:02:30 INFO - 'SHLVL': '1', 08:02:30 INFO - 'TERM': 'linux', 08:02:30 INFO - 'TMOUT': '86400', 08:02:30 INFO - 'USER': 'cltbld', 08:02:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:30 INFO - '_': '/tools/buildbot/bin/python'} 08:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:02:31 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 08:02:31 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 08:02:31 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 08:02:31 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 08:02:31 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 08:02:31 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 08:02:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:02:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:02:33 INFO - Installing collected packages: mozsystemmonitor 08:02:33 INFO - Running setup.py install for mozsystemmonitor 08:02:33 INFO - Successfully installed mozsystemmonitor 08:02:33 INFO - Cleaning up... 08:02:33 INFO - Return code: 0 08:02:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:33 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:33 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 08:02:33 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 08:02:33 INFO - Using env: {'DISPLAY': ':0', 08:02:33 INFO - 'HOME': '/home/cltbld', 08:02:33 INFO - 'LANG': 'en_US.UTF-8', 08:02:33 INFO - 'LANGUAGE': 'en_US:en', 08:02:33 INFO - 'LOGNAME': 'cltbld', 08:02:33 INFO - 'MAIL': '/var/mail/cltbld', 08:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:33 INFO - 'MOZ_NO_REMOTE': '1', 08:02:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:33 INFO - 'NO_EM_RESTART': '1', 08:02:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:33 INFO - 'PWD': '/builds/slave/test', 08:02:33 INFO - 'SHELL': '/bin/bash', 08:02:33 INFO - 'SHLVL': '1', 08:02:33 INFO - 'TERM': 'linux', 08:02:33 INFO - 'TMOUT': '86400', 08:02:33 INFO - 'USER': 'cltbld', 08:02:33 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:33 INFO - '_': '/tools/buildbot/bin/python'} 08:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:33 INFO - Downloading/unpacking jsonschema==2.5.1 08:02:33 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 08:02:33 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 08:02:33 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 08:02:33 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 08:02:33 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 08:02:33 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 08:02:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:02:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:02:35 INFO - warning: no files found matching '*.txt' 08:02:35 INFO - no previously-included directories found matching 'build' 08:02:35 INFO - no previously-included directories found matching 'dist' 08:02:35 INFO - no previously-included directories found matching '.git*' 08:02:35 INFO - Installing collected packages: jsonschema, functools32 08:02:35 INFO - Running setup.py install for functools32 08:02:35 INFO - warning: no files found matching '*.txt' 08:02:35 INFO - no previously-included directories found matching 'build' 08:02:35 INFO - no previously-included directories found matching 'dist' 08:02:35 INFO - no previously-included directories found matching '.git*' 08:02:36 INFO - Successfully installed jsonschema functools32 08:02:36 INFO - Cleaning up... 08:02:36 INFO - Return code: 0 08:02:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:36 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:36 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 08:02:36 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 08:02:36 INFO - Using env: {'DISPLAY': ':0', 08:02:36 INFO - 'HOME': '/home/cltbld', 08:02:36 INFO - 'LANG': 'en_US.UTF-8', 08:02:36 INFO - 'LANGUAGE': 'en_US:en', 08:02:36 INFO - 'LOGNAME': 'cltbld', 08:02:36 INFO - 'MAIL': '/var/mail/cltbld', 08:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:36 INFO - 'MOZ_NO_REMOTE': '1', 08:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:36 INFO - 'NO_EM_RESTART': '1', 08:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:36 INFO - 'PWD': '/builds/slave/test', 08:02:36 INFO - 'SHELL': '/bin/bash', 08:02:36 INFO - 'SHLVL': '1', 08:02:36 INFO - 'TERM': 'linux', 08:02:36 INFO - 'TMOUT': '86400', 08:02:36 INFO - 'USER': 'cltbld', 08:02:36 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:36 INFO - '_': '/tools/buildbot/bin/python'} 08:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:02:36 INFO - Cleaning up... 08:02:36 INFO - Return code: 0 08:02:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:36 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:36 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 08:02:36 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 08:02:36 INFO - Using env: {'DISPLAY': ':0', 08:02:36 INFO - 'HOME': '/home/cltbld', 08:02:36 INFO - 'LANG': 'en_US.UTF-8', 08:02:36 INFO - 'LANGUAGE': 'en_US:en', 08:02:36 INFO - 'LOGNAME': 'cltbld', 08:02:36 INFO - 'MAIL': '/var/mail/cltbld', 08:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:36 INFO - 'MOZ_NO_REMOTE': '1', 08:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:36 INFO - 'NO_EM_RESTART': '1', 08:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:36 INFO - 'PWD': '/builds/slave/test', 08:02:36 INFO - 'SHELL': '/bin/bash', 08:02:36 INFO - 'SHLVL': '1', 08:02:36 INFO - 'TERM': 'linux', 08:02:36 INFO - 'TMOUT': '86400', 08:02:36 INFO - 'USER': 'cltbld', 08:02:36 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:36 INFO - '_': '/tools/buildbot/bin/python'} 08:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:02:36 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 08:02:36 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 08:02:36 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 08:02:36 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 08:02:36 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 08:02:36 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 08:02:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02:39 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 08:02:39 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 08:02:39 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 08:02:39 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 08:02:39 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 08:02:39 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 08:02:39 INFO - Downloading docopt-0.6.1.tar.gz 08:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:39 INFO - Running setup.py install for blobuploader 08:02:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:39 INFO - Running setup.py install for requests 08:02:39 INFO - Running setup.py install for docopt 08:02:39 INFO - Successfully installed blobuploader requests docopt 08:02:39 INFO - Cleaning up... 08:02:39 INFO - Return code: 0 08:02:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:39 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')]} 08:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:39 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')]} 08:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:39 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:39 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 08:02:39 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 08:02:39 INFO - Using env: {'DISPLAY': ':0', 08:02:39 INFO - 'HOME': '/home/cltbld', 08:02:39 INFO - 'LANG': 'en_US.UTF-8', 08:02:39 INFO - 'LANGUAGE': 'en_US:en', 08:02:39 INFO - 'LOGNAME': 'cltbld', 08:02:39 INFO - 'MAIL': '/var/mail/cltbld', 08:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:39 INFO - 'MOZ_NO_REMOTE': '1', 08:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:39 INFO - 'NO_EM_RESTART': '1', 08:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:39 INFO - 'PWD': '/builds/slave/test', 08:02:39 INFO - 'SHELL': '/bin/bash', 08:02:39 INFO - 'SHLVL': '1', 08:02:39 INFO - 'TERM': 'linux', 08:02:39 INFO - 'TMOUT': '86400', 08:02:39 INFO - 'USER': 'cltbld', 08:02:39 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:39 INFO - '_': '/tools/buildbot/bin/python'} 08:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:40 INFO - Running setup.py (path:/tmp/pip-G5QCgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:40 INFO - Running setup.py (path:/tmp/pip-YoFobQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:40 INFO - Running setup.py (path:/tmp/pip-KmR2iE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:40 INFO - Running setup.py (path:/tmp/pip-1MQFLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:40 INFO - Running setup.py (path:/tmp/pip-_bai6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:40 INFO - Running setup.py (path:/tmp/pip-xKLgpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:40 INFO - Running setup.py (path:/tmp/pip-9O7hYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:40 INFO - Running setup.py (path:/tmp/pip-QnpMfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:40 INFO - Running setup.py (path:/tmp/pip-qNiwuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:40 INFO - Running setup.py (path:/tmp/pip-jKlOA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:40 INFO - Running setup.py (path:/tmp/pip-eXszBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:40 INFO - Running setup.py (path:/tmp/pip-zq5plW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:41 INFO - Running setup.py (path:/tmp/pip-O0RWye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:41 INFO - Running setup.py (path:/tmp/pip-YUyN0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:41 INFO - Running setup.py (path:/tmp/pip-QWzdWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:41 INFO - Running setup.py (path:/tmp/pip-6I76ST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:41 INFO - Running setup.py (path:/tmp/pip-WggB79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:41 INFO - Running setup.py install for manifestparser 08:02:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:41 INFO - Running setup.py install for mozcrash 08:02:41 INFO - Running setup.py install for mozdebug 08:02:41 INFO - Running setup.py install for mozdevice 08:02:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:41 INFO - Running setup.py install for mozfile 08:02:41 INFO - Running setup.py install for mozhttpd 08:02:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozinfo 08:02:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozInstall 08:02:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozleak 08:02:42 INFO - Running setup.py install for mozlog 08:02:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for moznetwork 08:02:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozprocess 08:02:42 INFO - Running setup.py install for mozprofile 08:02:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozrunner 08:02:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozscreenshot 08:02:42 INFO - Running setup.py install for moztest 08:02:43 INFO - Running setup.py install for mozversion 08:02:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:43 INFO - Cleaning up... 08:02:43 INFO - Return code: 0 08:02:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:43 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:43 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 08:02:43 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 08:02:43 INFO - Using env: {'DISPLAY': ':0', 08:02:43 INFO - 'HOME': '/home/cltbld', 08:02:43 INFO - 'LANG': 'en_US.UTF-8', 08:02:43 INFO - 'LANGUAGE': 'en_US:en', 08:02:43 INFO - 'LOGNAME': 'cltbld', 08:02:43 INFO - 'MAIL': '/var/mail/cltbld', 08:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:43 INFO - 'MOZ_NO_REMOTE': '1', 08:02:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:43 INFO - 'NO_EM_RESTART': '1', 08:02:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:43 INFO - 'PWD': '/builds/slave/test', 08:02:43 INFO - 'SHELL': '/bin/bash', 08:02:43 INFO - 'SHLVL': '1', 08:02:43 INFO - 'TERM': 'linux', 08:02:43 INFO - 'TMOUT': '86400', 08:02:43 INFO - 'USER': 'cltbld', 08:02:43 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:43 INFO - '_': '/tools/buildbot/bin/python'} 08:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:43 INFO - Running setup.py (path:/tmp/pip-Bn9lEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:43 INFO - Running setup.py (path:/tmp/pip-3I3Ag4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:43 INFO - Running setup.py (path:/tmp/pip-gVK9hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:43 INFO - Running setup.py (path:/tmp/pip-6Z7xpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:43 INFO - Running setup.py (path:/tmp/pip-rXudcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:43 INFO - Running setup.py (path:/tmp/pip-wQQYrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:43 INFO - Running setup.py (path:/tmp/pip-HV1CwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:43 INFO - Running setup.py (path:/tmp/pip-P7I14g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:43 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)) 08:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:43 INFO - Running setup.py (path:/tmp/pip-p5Pkug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:44 INFO - Running setup.py (path:/tmp/pip-qUNr8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:44 INFO - Running setup.py (path:/tmp/pip-LxVTjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:44 INFO - Running setup.py (path:/tmp/pip-RpsQch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:44 INFO - Running setup.py (path:/tmp/pip-UiN2wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:44 INFO - Running setup.py (path:/tmp/pip-870f_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:44 INFO - Running setup.py (path:/tmp/pip-unI4MD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:44 INFO - Running setup.py (path:/tmp/pip-ItJuN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:44 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)) 08:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:44 INFO - Running setup.py (path:/tmp/pip-InUOYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:44 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)) 08:02:44 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)) 08:02:44 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)) 08:02:44 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)) 08:02:44 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)) 08:02:44 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)) 08:02:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:44 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 08:02:44 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 08:02:44 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 08:02:44 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 08:02:44 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 08:02:44 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 08:02:46 INFO - Downloading blessings-1.6.tar.gz 08:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:46 INFO - Installing collected packages: blessings 08:02:46 INFO - Running setup.py install for blessings 08:02:46 INFO - Successfully installed blessings 08:02:46 INFO - Cleaning up... 08:02:46 INFO - Return code: 0 08:02:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:47 INFO - Reading from file tmpfile_stdout 08:02:47 INFO - Current package versions: 08:02:47 INFO - argparse == 1.2.1 08:02:47 INFO - blessings == 1.6 08:02:47 INFO - blobuploader == 1.2.4 08:02:47 INFO - docopt == 0.6.1 08:02:47 INFO - functools32 == 3.2.3-2 08:02:47 INFO - jsonschema == 2.5.1 08:02:47 INFO - manifestparser == 1.1 08:02:47 INFO - mozInstall == 1.12 08:02:47 INFO - mozcrash == 1.0 08:02:47 INFO - mozdebug == 0.1 08:02:47 INFO - mozdevice == 0.49 08:02:47 INFO - mozfile == 1.2 08:02:47 INFO - mozhttpd == 0.7 08:02:47 INFO - mozinfo == 0.9 08:02:47 INFO - mozleak == 0.1 08:02:47 INFO - mozlog == 3.4 08:02:47 INFO - moznetwork == 0.27 08:02:47 INFO - mozprocess == 0.24 08:02:47 INFO - mozprofile == 0.28 08:02:47 INFO - mozrunner == 6.13 08:02:47 INFO - mozscreenshot == 0.1 08:02:47 INFO - mozsystemmonitor == 0.3 08:02:47 INFO - moztest == 0.8 08:02:47 INFO - mozversion == 1.4 08:02:47 INFO - psutil == 3.1.1 08:02:47 INFO - requests == 1.2.3 08:02:47 INFO - wsgiref == 0.1.2 08:02:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:47 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 08:02:47 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 08:02:47 INFO - Using env: {'DISPLAY': ':0', 08:02:47 INFO - 'HOME': '/home/cltbld', 08:02:47 INFO - 'LANG': 'en_US.UTF-8', 08:02:47 INFO - 'LANGUAGE': 'en_US:en', 08:02:47 INFO - 'LOGNAME': 'cltbld', 08:02:47 INFO - 'MAIL': '/var/mail/cltbld', 08:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:47 INFO - 'MOZ_NO_REMOTE': '1', 08:02:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:47 INFO - 'NO_EM_RESTART': '1', 08:02:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:47 INFO - 'PWD': '/builds/slave/test', 08:02:47 INFO - 'SHELL': '/bin/bash', 08:02:47 INFO - 'SHLVL': '1', 08:02:47 INFO - 'TERM': 'linux', 08:02:47 INFO - 'TMOUT': '86400', 08:02:47 INFO - 'USER': 'cltbld', 08:02:47 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:47 INFO - '_': '/tools/buildbot/bin/python'} 08:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 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)) 08:02:47 INFO - Cleaning up... 08:02:47 INFO - Return code: 0 08:02:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:47 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 0x23ae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2720630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, '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': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', '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 08:02:47 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 08:02:47 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 08:02:47 INFO - Using env: {'DISPLAY': ':0', 08:02:47 INFO - 'HOME': '/home/cltbld', 08:02:47 INFO - 'LANG': 'en_US.UTF-8', 08:02:47 INFO - 'LANGUAGE': 'en_US:en', 08:02:47 INFO - 'LOGNAME': 'cltbld', 08:02:47 INFO - 'MAIL': '/var/mail/cltbld', 08:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:47 INFO - 'MOZ_NO_REMOTE': '1', 08:02:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:02:47 INFO - 'NO_EM_RESTART': '1', 08:02:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:47 INFO - 'PWD': '/builds/slave/test', 08:02:47 INFO - 'SHELL': '/bin/bash', 08:02:47 INFO - 'SHLVL': '1', 08:02:47 INFO - 'TERM': 'linux', 08:02:47 INFO - 'TMOUT': '86400', 08:02:47 INFO - 'USER': 'cltbld', 08:02:47 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:47 INFO - '_': '/tools/buildbot/bin/python'} 08:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:02:47 INFO - Cleaning up... 08:02:47 INFO - Return code: 0 08:02:47 INFO - Running post-action listener: _resource_record_post_action 08:02:47 INFO - Running post-action listener: _start_resource_monitoring 08:02:47 INFO - Starting resource monitoring. 08:02:47 INFO - [mozharness: 2017-02-03 16:02:47.629179Z] Finished create-virtualenv step (success) 08:02:47 INFO - [mozharness: 2017-02-03 16:02:47.629639Z] Running install step. 08:02:47 INFO - Running pre-action listener: _resource_record_pre_action 08:02:47 INFO - Running main action method: install 08:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:47 INFO - Reading from file tmpfile_stdout 08:02:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:47 INFO - Reading from file tmpfile_stdout 08:02:47 INFO - Output received: 08:02:47 INFO - Usage: mozinstall [options] installer 08:02:47 INFO - Options: 08:02:47 INFO - -h, --help show this help message and exit 08:02:47 INFO - -d DEST, --destination=DEST 08:02:47 INFO - Directory to install application into. [default: 08:02:47 INFO - "/builds/slave/test"] 08:02:47 INFO - --app=APP Application being installed. [default: firefox] 08:02:47 INFO - mkdir: /builds/slave/test/build/application 08:02:47 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'] 08:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:03:04 INFO - Reading from file tmpfile_stdout 08:03:04 INFO - Output received: 08:03:04 INFO - /builds/slave/test/build/application/firefox/firefox 08:03:04 INFO - Running post-action listener: _resource_record_post_action 08:03:04 INFO - [mozharness: 2017-02-03 16:03:04.371531Z] Finished install step (success) 08:03:04 INFO - [mozharness: 2017-02-03 16:03:04.371815Z] Running run-tests step. 08:03:04 INFO - Running pre-action listener: _resource_record_pre_action 08:03:04 INFO - Running main action method: run_tests 08:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:04 INFO - Python 2.7.3 08:03:04 INFO - Return code: 0 08:03:04 INFO - grabbing minidump binary from tooltool 08:03:04 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')]} 08:03:04 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 0x27a9850>, '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 0x271e6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x271eb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:04 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 08:03:04 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 08:03:04 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 08:03:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:04 INFO - Return code: 0 08:03:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:03:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:03:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 08:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 08:03:04 INFO - Using env: {'DISPLAY': ':0', 08:03:04 INFO - 'HOME': '/home/cltbld', 08:03:04 INFO - 'LANG': 'en_US.UTF-8', 08:03:04 INFO - 'LANGUAGE': 'en_US:en', 08:03:04 INFO - 'LOGNAME': 'cltbld', 08:03:04 INFO - 'MAIL': '/var/mail/cltbld', 08:03:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:04 INFO - 'MOZ_NO_REMOTE': '1', 08:03:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:03:04 INFO - 'NO_EM_RESTART': '1', 08:03:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:04 INFO - 'PWD': '/builds/slave/test', 08:03:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:04 INFO - 'SHELL': '/bin/bash', 08:03:04 INFO - 'SHLVL': '1', 08:03:04 INFO - 'TERM': 'linux', 08:03:04 INFO - 'TMOUT': '86400', 08:03:04 INFO - 'USER': 'cltbld', 08:03:04 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295', 08:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:04 INFO - '_': '/tools/buildbot/bin/python'} 08:03:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 08:03:04 INFO - mozversion application_buildid: 20170203145631 08:03:04 INFO - mozversion application_changeset: c02020dd7e6e190b046e0bdc992a75b647c4cffa 08:03:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:03:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:04 INFO - mozversion application_name: Firefox 08:03:04 INFO - mozversion application_remotingname: firefox-dev 08:03:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:03:04 INFO - mozversion application_vendor: Mozilla 08:03:04 INFO - mozversion application_version: 53.0a2 08:03:04 INFO - mozversion platform_buildid: 20170203145631 08:03:04 INFO - mozversion platform_changeset: c02020dd7e6e190b046e0bdc992a75b647c4cffa 08:03:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:03:04 INFO - mozversion platform_version: 53.0a2 08:03:04 INFO - using testdate: 1486137784 08:03:04 INFO - actual date: 1486137784 08:03:04 INFO - starting webserver on 'localhost:33870' 08:03:04 INFO - SUITE-START | Running 2 tests 08:03:04 INFO - TEST-START | dromaeo_css 08:03:04 INFO - operating with platform_type : linux_ 08:03:04 INFO - Initialising browser for dromaeo_css test... 08:03:04 INFO - TEST-INFO | started process 4106 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo4YTpw/profile http://localhost:33870/getInfo.html) 08:03:08 INFO - PROCESS | 4106 | __metrics Screen width/height:1600/1200 08:03:08 INFO - PROCESS | 4106 | colorDepth:24 08:03:08 INFO - PROCESS | 4106 | Browser inner width/height: 1024/697 08:03:08 INFO - PROCESS | 4106 | __metrics 08:03:09 INFO - PROCESS | 4106 | 1486137788999 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:03:09 INFO - PROCESS | 4106 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:03:09 INFO - PROCESS | 4106 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:03:09 INFO - PROCESS | 4106 | console.error: PushService: 08:03:09 INFO - PROCESS | 4106 | stateChangeProcessEnqueue: Error transitioning state 08:03:09 INFO - PROCESS | 4106 | UnknownError 08:03:10 INFO - TEST-INFO | 4106: exit 0 08:03:10 INFO - Browser initialized. 08:03:10 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:10 INFO - TEST-INFO | started process 4199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo4YTpw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:22 INFO - PROCESS | 4199 | RSS: Main: 138051584 08:03:22 INFO - PROCESS | 4199 | 08:06:22 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:33870/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33870/tests/dromaeo/cssquery-ext.html) 08:06:23 INFO - PROCESS | 4199 | RSS: Main: 243695616 08:06:23 INFO - PROCESS | 4199 | 08:08:11 INFO - PROCESS | 4199 | ************************* 08:08:11 INFO - PROCESS | 4199 | A coding exception was thrown and uncaught in a Task. 08:08:11 INFO - PROCESS | 4199 | 08:08:11 ERROR - PROCESS | 4199 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:08:11 INFO - PROCESS | 4199 | Full stack: 08:08:11 INFO - PROCESS | 4199 | ************************* 08:09:25 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:33870/tests/dromaeo/cssquery-ext.html (next: http://localhost:33870/tests/dromaeo/cssquery-jquery.html) 08:09:26 INFO - PROCESS | 4199 | RSS: Main: 221216768 08:09:26 INFO - PROCESS | 4199 | 08:12:28 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:33870/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33870/tests/dromaeo/cssquery-mootools.html) 08:12:28 INFO - PROCESS | 4199 | RSS: Main: 173563904 08:12:28 INFO - PROCESS | 4199 | 08:15:30 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:33870/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33870/tests/dromaeo/cssquery-prototype.html) 08:15:31 INFO - PROCESS | 4199 | RSS: Main: 171778048 08:15:31 INFO - PROCESS | 4199 | 08:18:33 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:33870/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33870/tests/dromaeo/cssquery-yui.html) 08:18:34 INFO - PROCESS | 4199 | RSS: Main: 173879296 08:18:34 INFO - PROCESS | 4199 | 08:21:36 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:33870/tests/dromaeo/cssquery-yui.html (next: http://localhost:33870/tests/dromaeo/cssquery-dojo.html) 08:21:37 INFO - PROCESS | 4199 | RSS: Main: 263770112 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | __start_tp_report 08:21:37 INFO - PROCESS | 4199 | _x_x_mozilla_page_load 08:21:37 INFO - PROCESS | 4199 | _x_x_mozilla_page_load_details 08:21:37 INFO - PROCESS | 4199 | |i|pagename|runs| 08:21:37 INFO - PROCESS | 4199 | |0;dojo.html;3158.0841555948778;3289.079057863799;3293.7035666789984;3313.1717070732316;3330.050935483386;5484.670919744818;5554.805581804632;5561.888762224763;5562.888742225153;5567.415421380758;5512.972435137829;5565.888682226353;5565.9443405565935;5578.581606379517;5615.241942337783;4965.453800081988;4982.800687972477;5013.874653133665;5064.113780088488;5069.340985671876;4917.3607431033915;5024.296598476216;5072.036313100518;5072.923906141393;5078.974605126969;4912.484189160123;4919.065377578267;4929.852104436873;4940.629452791037;5006.599472042246;4999.250112483121;5014.172661510846;5020.497950204986;5028.522290382408;5041.59667226621;4902.460729319772;4944.258361245806;4983.127952608315;5006.69959802413;5020.648554601179;5329.360476742777;5384.6769193848495;5394.541463975567;5431.158170483542;5447.5641948644015;5914.467697907209;5915.674637894914;5923.08192230205;5931.525477961753;5953.791617293417;5911.970440147815;5956.344802071769;5977.103434484862;5978.432048955342;5980.461758441717;5348.224507446437;5389.461053894579;5481.342238931354;5522.6134170607675;5569.944300556983;6008.188894499274;6015.969920150371;6035.698215089228;6039.094135879565;6059.091136329498;5792.333881603585;5829.329627092854;5833.562482813721;5837.357890632027;5839.357670656224;5748.798792042258;5770.019096753478;5833.7083145842535;5864.824055278348;5929.881402371929;5830;5864.06175011996;5908.793192238251;5942.1978032965;5958.582899197101;5926.674032928144;5928.79249226275;5936.7031648417405;5938.703064846741;5938.732757025944;4467.106578684276;4500.144972455223;4575.10785396851;4587.655925805578;4604.723716577016;3964.960350396459;4046.4537287466405;4054.2094291613444;4058.3912413138264;4101.91796164075;5707.200991861143;5710.71446427677;5714.200011998323;5714.714264286769;5737.311522617312;5742.253507044141;5853.356130825606;5897.734601942923;5976.8804623908145;5994.940050599438;5676.659400435987;5748.45389687979;5749.597528172988;5763.625364351304;5783.624064435882;5087.567556757717;5088.567471764942;5090.618203634742;5094.745262736848;5122.078025955289;5928.288605367384;5953.523718102455;6109.480694140959;6172.444479996732;6174.598651087665;5866.442687944638;5889.646621202742;5910.143029260647;5970.522358211246;6010.278766548042;6138.846528836815;6141.324454310112;6147.877042459215;6148.292946311052;6148.9692551536955;5722.284714410699;5812.9709351452975;5874.324452687824;5879.647221166743;5883.558733094949;5875.118732190205;5877.647341159544;5923.763049477972;5926.407359264039;6009.849753756191;5773.566982476415;5780.508656764222;5788.855278618068;5827.70861456917;5830.5627077968475;5771.884562308815;5779.537636989115;5784.768609255751;5786.392428794916;5790.536757059341;5592.496675299242;5605.607607467438;5675.177099320493;5730.914036289376;5744.425557444222;5824.126381042708;5830.854228644148;5831.970840145772;5833.154192642128;5852.297724272944;5672.9221447924765;5695.544356451391;5705.572082093942;5708.828735137952;5712.37163911961 08:21:37 INFO - PROCESS | 4199 | |1;ext.html;573.635741304272;608.0544752909226;619.4177473175215;637.9362063793619;668.9096971908797;37057.81471092644;48037;49836;49836;49895.00209995802;52626;53181;53181.46818531813;53267;53267.73366133174;8787.736367908972;9395.906040939606;9587.952060239724;9590.568424420882;9593.376430532027;8779.429337093101;11269.718257043558;11442;11550.30698158109;11906.226095303777;11647.883521164807;11688;11701.824472632918;11728.882711172908;11737.471813768358;11628.941855290712;11687.883121168765;11698.590549330733;11710.590129345517;11710.64868053964;11443.656690299222;11507.309561426258;11526.827097593543;11530.654080377519;11535.826962595567;48244.51755482436;48368.51631483711;48376.758116209545;48420.75789621029;48861;4537.38745269391;4540.205464043779;4620.168369693454;4674.298855171717;4704.988427488072;34957;35028;35058;35242;36478.63521364806;4692.906141877177;4721.268203428474;4727.267273572602;4735.526447355272;4764.737939413331;404747;405017;405561;406393.99999999703;406972;72345.6382718083;72821.6358918202;72853;72881;72904;77588.61205693935;77700;77767.61116194383;77798.6110069446;78007.21992780112;19077.713834292495;19689.113989870202;19701.226889580008;19711.11299991504;19729.704054439197;14256;14509.85490145106;14796.408143674133;15395.615109622126;15553.144577048144;20545.280915167896;21969.681819090907;22127.889360553418;22168;22215.777842221694;7484.176740558534;7550.7734767957045;7685.193054729285;7728.304452599293;7732.149463559004;1004.2618675273668;1011.4538149399389;1016.5222345497605;1019.3221507697447;1025.4821315235758;35000.64999350025;35288.82355588257;35329.1167720809;35463.645363546035;35724.28551428957;1091.6233899304727;1157.288267715349;1171.800793865045;1173.436750359832;1209.3227792436264;18528.351507697167;18567.25730970746;18680.045546533496;18800.811991879906;18870.245190192512;503950;508601;509238;509940;510045;70995;71342.28657713356;71449;71697;71784;1565.725997950377;1601;1607.252627528205;1611.4923799003511;1622.0348892408997;31214;32552.837235814615;32930.341393171526;33011;33304.83347583246;60151.69924150351;60856;60924.39075609364;60948.39051609604;61113;298858;300156;300530;300872;301802;39594.80202598969;44296.33555496734;45375.319370210134;45775.542244577126;46719.76640116778;53403;53629;54020.45979540311;54334.18498722601;54508.18237726516;44517.33224001707;45881.311780322656;46582.76708616571;46631;46892.7655361721;43603.345949810144;43703.56296436995;44298.55701442944;45326.54673453223;45859.54140458686;40254.798726006185;40890.59109408987;41454.792726036176;44300.556994429644;44689.55310446854;34142.658573413944;36183.27633447369;36690.63309366872;37084.44373334348;37126;27749.722502775523;27869;27960.440791183657;28204;28234.576481352386 08:21:37 INFO - PROCESS | 4199 | |2;jquery.html;420.2753960565444;429.22524842659027;435.3100335967491;437.14101789982686;445.41205608596664;19629;20796.480087997807;21829.890850545766;21989.34031979041;22047.448813779625;22769.54460910781;22957;22971.885140574275;23395.88302058487;23453.296401107953;6125.264968203822;6135.417135372132;6344.524160687944;6352.269489008754;6352.364763523634;6079.240094988127;6088.1476593276975;6118.694065296718;6129.264488261415;6161.537884658668;5920.4671579557835;6074.696265186745;6080.422359875804;6081.148639190517;6136.815895523141;6143.139960405524;6186.876262474748;6193.566450348478;6194.50443964484;6203.100550420164;5513.42109078549;5526.364468086141;5561.888762224733;5567.777288908439;5568.52667523257;26716;26893.327666808487;26922;26985.32536686579;27115.322116947038;2881.5245484495035;2985.537241727514;3031.5301128325145;3072.3548054908497;3089.90730278092;15354.769678454832;16026;16278.186090695537;16508;16530.58673533159;2089.8328133749264;2118.555103428282;2123.3948324727535;2168.4036889855256;2170.739511258659;89177;91559.99999999933;91654.54172729094;93155;93624;11737.530498780125;12019.098567607465;12109.69725756863;12171.208871423507;12392.256464611972;17165.57086072833;17183.914080429517;17185.226664800113;17188.65622687539;17200.569985750455;9041.593128309241;9476;9495.240380769523;9532.475713835667;9545.284103692251;9374.62501499946;9405.952970235105;9439.575219115157;9567.043295670377;9569.3779904306;5346.224797404355;5412.675239485643;5413.350397952271;5438.809641662523;5585.078462053716;2071.78246284138;2119.629064913634;2171.8805465699224;2173.815225705801;2178.880161591096;901.3195037746459;914.07221670005;916.5188276154938;928.661038720862;939.793245485992;21184.89407552983;21286.148554057974;21547.461313467287;21769.455763606038;21770.238041668465;907.9092090790868;911.2618778789202;922.3820040572832;939.0984654731398;944.7874228298577;11312.6040588579;11713.355765432812;11794.88205117955;11857.644270671894;12043.819342709867;302999;304313;304331;304974;405993;111473;112726.43636781763;113314;114123;114681.4265928665;1025.2259544044348;1027.4554486122368;1049.92125590581;1070.1331921143899;1070.400575677623;18958.43124706261;19374;19445.51386215356;19531;19581;37363.439548406255;41471.58528414677;41988.3701744468;43101.56898431101;46017;63623.3637663636;63955.680221598595;64330.678346607965;64416.355836441035;64495.677521612095;6433.806985790434;8736.733173689652;8768.25469835071;9010.5945232466;9174.394480965937;9718.076782705632;12696.555620553605;12746.617601471971;12758.298293593942;13687.45250190021;12206.267623942593;12440.31578263168;12504.812427813407;12661.620151395473;12764.723527647533;11094.167937404889;11506.82739758921;11751.647450576496;12096.395180241128;12141.878581214429;12109.212901161396;12176.75646487083;12412.749305479392;12897.613071607866;12976.545820896223;8912.28701703875;9050.502222377832;9055.728328150166;9113.498757568132;9147.496887671243;5863.560232982458;8940;9657.3239873211;11684;11890 08:21:37 INFO - PROCESS | 4199 | |3;mootools.html;1366.7608168570498;1460.97808532872;1489.7616381378975;1502.4215676964373;1506.4275575281392;7587.810304742384;8268.669253229871;8440.071592124868;8506.106858779833;8770.166834150761;8605.741827745178;8690.522021288827;8695.434796738207;8701.129887011311;8706.564671766402;2115.23851413491;2382.321038504031;2417.9395515112087;2420.0561780905373;2423.2851308863837;2202;2351.870647114408;2426.915057972971;2428.781409673129;2453.3130723397476;2392.545416370893;2397.7242617098996;2414.239514552919;2414.7464516225805;2422.806175505964;2281.235786011688;2288.7482376938524;2292.541491701658;2299.482616411294;2299.9655005174936;2287.3709729824263;2383.285014495644;2387.8209134315;2392.8923198455936;2393.497365553236;8621.396502244845;8680.913190868137;8704.216620504121;8709.95645021777;8713;5038.093143234217;5190.273361729361;5193.402758682762;5196;5201.505856943584;3126.937461250762;3274.4760838266;3295.1597342677705;3321.5183798349344;3397.6432474590306;1294.5080869269711;1331.2478449675903;1334.4795529743408;1337.0840973932886;1390.388229179168;10894.074003709695;10957.726056848642;10996.945015274872;11027.338359698444;11059.612913548068;4226.788660566959;4441.933370999438;4442.3336499524685;4506.571875671806;4520.59314661682;9321.021292764297;9323.25413966881;9331.766705832408;9334.859977100348;9350.485723285148;5069.6451248413;5070.569001634903;5075.061113693979;5080.517350851663;5080.54275115234;4865.343178670834;4918.3360246367;4986.725730084808;5014.047331007097;5055.292259083732;3882.8446862125197;3951.6245956634075;3953;3987.980060099681;4020.8994775130273;1224.5040758492826;1231.587418214908;1235.110720281396;1238.0343332200898;1241.2986662535639;992.9553170107218;1008.8839783424853;1025.4411345816548;1026.8767747870304;1027.9897201028043;5759.625624334405;5775;5810.564207684441;5883.7058147092475;5909.113632955005;1039.9720544937402;1063.2716589136467;1064.7497838008062;1074.5460117241057;1104.8232282834708;3760.511133552621;3919.412088186795;4197.8320867164975;4287.2711639021445;4410.4927933287445;9420.152186303265;9570.33007689469;9596.760080997892;9598.9040109598;9607.183389411979;9482.810343793086;9508.809823803485;9534;9536.570854311572;9547.045295470536;7594.696212151563;7960.2039796019935;7983.520988740578;8206.384521160817;8209.220124088202;5787.305523337226;6035.275766907999;6085.817425477243;6161.876762464815;6197.349278325711;9394.201492873182;9556.044395560388;9558;9591.088846559845;9699.806003880023;8419.36854735885;9401.482918439551;9532.52337383142;9637.469939153414;9685.32202745801;6737.461003119641;7416.851662966603;7575.583342916192;7610.96194519046;7793.64928578204;8313.750587482386;8333.458325209085;8424.073351931405;8449.070602233627;8516.872246916426;7078.929210707827;7141.892871606826;7181.820454488679;7208.747693830901;7225.891611625935;7210.531315464478;7265.12818461788;7307.050083489113;7325.523840950321;7413.555186688816;6100.542459315658;6337.683115844097;6362.904556431749;6419.2296924369375;6428.614283143026;4845.3216549682365;4857.247126695368;4932.827351042696;4959.330490383896;4965.1807451770055;4990.251462280687;5061.974690126526;5081.364829396325;5097.490250974873;5098.1842905135 08:21:37 INFO - PROCESS | 4199 | |4;prototype.html;411.90114372550585;413.1942711712162;414.8879802453338;419.61240541319904;424.535134028239;5357.812476563321;5363.517283444489;5377.542908852743;5418.160185171295;5440.129579267323;5174.793008279665;5198.298229738984;5331.093714068617;5356.91964620531;5367.543758780508;4276.144771045788;4291.249031419505;4300.677449191316;4310.935335969963;4318.870433886973;4291.785410729467;4293.355996600504;4304.655627549789;4311.353297005458;4313.913721725564;4138.6482149017365;4248.532661407243;4290.721103128302;4304.010077682135;4309.59058889405;4260.531341552427;4269.978650106761;4270.825522981174;4287.142571485684;4301.720388174758;3905.824237909301;3908.9413658795143;3916.784576848272;3923.4899463069905;3926.7840268785208;4972.204447288416;5182.196759502283;5212.400573934008;5247.370315562119;5282.154855223184;4865.781039853215;4879.756012199411;4887.169181239198;4894.755262236874;4898.02039592083;5652.660840349592;5680.943190568083;5688.829335119953;5697.401772813836;5714.457126572969;4067;4104.91790164198;4147.605977432139;4166.895827604304;4187.560306167869;5488.945110548883;5677.977963966526;5777;5826.941730582682;5829.650220986754;4168;4236.576342365739;4250.787460626956;4258.254805409041;4261.488621365394;4283.5502272260965;4283.629238643604;4293.484781826139;4313.180495705806;4324.675649326313;4612.97693511537;4697.436307643105;4710.62315014798;4841.176999910023;4841.9031619367415;5628.437156284339;5648.209250704905;5650.485533937441;5651.519620832275;5658.7170641467765;1401.3833913078213;1425.3799597175262;1428.3929330034694;1438.9712205755827;1440.9567712968628;996.7059717383389;997.6558087459815;998.557085012165;1004.1724062206714;1016.3139880580578;5309.867253318621;5345.224942383333;5355.544778693777;5403.621746477709;5409.459054094559;5549.556035517149;5594.692291923983;5622.212890195376;5638.915416268759;5667.858303542444;5113.51421614946;5293.206019097166;5347.235412314005;5350.545203657655;5362.195670649356;2923.444545536341;2950.704929507032;2957.659869115036;2966.9703302967127;2975.4941659917868;5586.608937374426;5750.84983003401;5767.826965191051;5801.274840644919;5951.375105613848;5849.151872978395;5861.17943487912;5866.67733274674;5908.350081491032;5916.645001299936;5992.951233534027;5994.610350327215;5997.580169388101;5998.310194327708;5999.400059994053;5591.692456914909;5755.65466072037;5762.510186634183;5771.9134212986;5779.711014449176;5458.781648734006;5600.747966341443;5723.427657234243;5731.8853622928145;5732.71336433185;5844.503217226584;5980.518084994678;5981.162637230878;5984.444044548536;5990.400959903975;4261.616454519108;4269.701120921506;4271.530131685574;4274.551172126882;4280.336547835091;5705.971470142623;5721.799737009184;5738.942610573841;5779.439551321204;5831.562632802472;5609.49514543693;5618.2134501170685;5620.859478512906;5633.788735421904;5641.830745077655;5404.595105083772;5632.493075623213;5636.408177141341;5642.887142257214;5644.971775141098;5572.60991730575;5576.665400076008;5610.410906854885;5611.691356975405;5632.493075623213;5633.605647604629;5634.74643641029;5647.435256474319;5662.867426514631;5662.971685141548;5325.588718989394;5458.8908221834545;5511.669299842022;5513.944860551504;5547.583931205095 08:21:37 INFO - PROCESS | 4199 | |5;yui.html;820.4953953318711;831.1065604475191;844.5692696724668;909.1543468942212;919.793046564523;15619.140947247897;16731;16744.497665070037;16757.329706811728;16757.664846703057;16313.347466101372;16481.91759041206;16505.83494165058;16510.091944943055;16515.83484165158;689.0731965506399;721.5418209436992;795.5306369242146;834.3200291762195;836.3392919593514;837.4347315561971;838.4591938199858;838.9580520973957;839.236294971576;839.2782207301716;884.8849649545582;885.9822803543925;886.5522910929972;886.7118186589352;887.40987243483;717.347214035228;750.3284560318511;822.6257053040852;858.9613467393982;877.855153899609;636.8280564247674;636.9904451433232;637.2607774981;637.4199478474612;637.5473413876153;15780.60548486297;15819.209039547977;15836.76244856328;15847.286872090783;16079.196040197943;15145.320504810696;15867.682646347124;16055.116968566725;16139.919300403422;16420.1789910504;12819.615411537668;12853.485860565566;12886.87113128866;12935.353232338346;13151.21092734439;3965.7422267552515;4024.557298697141;4028.4561584186054;4055.5336136344404;4067.959320406788;78597.60701196457;79757.60121199414;79793.60103199446;80060.59969700173;80191.59904200441;12258.632241032783;12564;12649.620511384674;12672.619821405373;12840.357982101044;22312.330630081124;22556.210532631278;22644.320670379915;22881;23078;9094.636214551476;9155;9207.907920920705;9286.535673216313;9400.764980875396;4755.286706993978;4807.399075115611;4846.75766211688;4909.042736666316;4983.20464380369;3690.6493883081066;3720.4419337099653;3752.3433399155037;3772.773633581939;3787.2614840106467;650.2197363164222;661.3419647450817;668.4017804065431;669.7990602819135;676.7800464848943;694.6422592364936;695.9756408525677;705.5202462325668;712.0956385390525;739.840934199139;11340.886591134147;11496.77006459866;11553.711157221136;11737.413129343498;12034.759304813855;658.0984051848902;658.8385845467795;670.6345041952147;672.495628278791;684.1926526698544;5263.6841789492755;5558.610897237155;5599.776008959596;5661.094224923992;5681.488666020078;78464.60767696126;78552.60723696345;80574.19425805785;80779.5961020203;82236;55127;59704.402955970756;61500;68480.6575967117;68667.65666171638;59405.70297148487;60927.69536152291;61919.690401547705;61928;63424;10325.845112323472;10654.094401975919;10676.346924322736;10786.137109031415;10807.405592692163;44997.10005799931;45113.32330014987;45764;46003.53996459993;46255.76872115618;51197;54773.72613136909;55484;56848;58068;12533.989511098072;12857.742845143232;12922.612321630366;12934.611961641165;13069.346532673519;13794.103383280055;14018.579442616738;14186.290685465892;14326.355314011103;15550.61123471877;15065.246737663292;15631.374745010073;15837.920810395874;16584.585385365463;16742.91628541898;15112.622184445478;15435.073895566302;15454.922725386301;15613.375464981273;16266.186690665656;14480.85519144795;14551.636209094857;15330.310136044129;15339.227158106703;15693.686126277182;11735.941320293685;11753.941230293794;12104.071832949432;12181.208221465577;13390.732185356434;12379.319137447526;12453.247957870743;12579.496820127093;12616.936915315364;12688.936555317534 08:21:37 INFO - PROCESS | 4199 | __end_tp_report 08:21:37 INFO - PROCESS | 4199 | __start_cc_report 08:21:37 INFO - PROCESS | 4199 | _x_x_mozilla_cycle_collect,973 08:21:37 INFO - PROCESS | 4199 | __end_cc_report 08:21:37 INFO - PROCESS | 4199 | __startTimestamp1486138897235__endTimestamp 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | ------- Summary: start ------- 08:21:37 INFO - PROCESS | 4199 | Number of tests: 6 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | [#0] dojo.html Cycles:165 Average:5496.24 Median:5744.43 stddev:618.71 (10.8%) stddev-sans-first:592.79 08:21:37 INFO - PROCESS | 4199 | Values: 3158.1 3289.1 3293.7 3313.2 3330.1 5484.7 5554.8 5561.9 5562.9 5567.4 5513.0 5565.9 5565.9 5578.6 5615.2 4965.5 4982.8 5013.9 5064.1 5069.3 4917.4 5024.3 5072.0 5072.9 5079.0 4912.5 4919.1 4929.9 4940.6 5006.6 4999.3 5014.2 5020.5 5028.5 5041.6 4902.5 4944.3 4983.1 5006.7 5020.6 5329.4 5384.7 5394.5 5431.2 5447.6 5914.5 5915.7 5923.1 5931.5 5953.8 5912.0 5956.3 5977.1 5978.4 5980.5 5348.2 5389.5 5481.3 5522.6 5569.9 6008.2 6016.0 6035.7 6039.1 6059.1 5792.3 5829.3 5833.6 5837.4 5839.4 5748.8 5770.0 5833.7 5864.8 5929.9 5830.0 5864.1 5908.8 5942.2 5958.6 5926.7 5928.8 5936.7 5938.7 5938.7 4467.1 4500.1 4575.1 4587.7 4604.7 3965.0 4046.5 4054.2 4058.4 4101.9 5707.2 5710.7 5714.2 5714.7 5737.3 5742.3 5853.4 5897.7 5976.9 5994.9 5676.7 5748.5 5749.6 5763.6 5783.6 5087.6 5088.6 5090.6 5094.7 5122.1 5928.3 5953.5 6109.5 6172.4 6174.6 5866.4 5889.6 5910.1 5970.5 6010.3 6138.8 6141.3 6147.9 6148.3 6149.0 5722.3 5813.0 5874.3 5879.6 5883.6 5875.1 5877.6 5923.8 5926.4 6009.8 5773.6 5780.5 5788.9 5827.7 5830.6 5771.9 5779.5 5784.8 5786.4 5790.5 5592.5 5605.6 5675.2 5730.9 5744.4 5824.1 5830.9 5832.0 5833.2 5852.3 5672.9 5695.5 5705.6 5708.8 5712.4 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | [#1] ext.html Cycles:180 Average:61008.98 Median:406683.00 stddev:109017.70 (26.8%) stddev-sans-first:109229.09 08:21:37 INFO - PROCESS | 4199 | Values: 573.6 608.1 619.4 637.9 668.9 37057.8 48037.0 49836.0 49836.0 49895.0 52626.0 53181.0 53181.5 53267.0 53267.7 8787.7 9395.9 9588.0 9590.6 9593.4 8779.4 11269.7 11442.0 11550.3 11906.2 11647.9 11688.0 11701.8 11728.9 11737.5 11628.9 11687.9 11698.6 11710.6 11710.6 11443.7 11507.3 11526.8 11530.7 11535.8 48244.5 48368.5 48376.8 48420.8 48861.0 4537.4 4540.2 4620.2 4674.3 4705.0 34957.0 35028.0 35058.0 35242.0 36478.6 4692.9 4721.3 4727.3 4735.5 4764.7 404747.0 405017.0 405561.0 406394.0 406972.0 72345.6 72821.6 72853.0 72881.0 72904.0 77588.6 77700.0 77767.6 77798.6 78007.2 19077.7 19689.1 19701.2 19711.1 19729.7 14256.0 14509.9 14796.4 15395.6 15553.1 20545.3 21969.7 22127.9 22168.0 22215.8 7484.2 7550.8 7685.2 7728.3 7732.1 1004.3 1011.5 1016.5 1019.3 1025.5 35000.6 35288.8 35329.1 35463.6 35724.3 1091.6 1157.3 1171.8 1173.4 1209.3 18528.4 18567.3 18680.0 18800.8 18870.2 503950.0 508601.0 509238.0 509940.0 510045.0 70995.0 71342.3 71449.0 71697.0 71784.0 1565.7 1601.0 1607.3 1611.5 1622.0 31214.0 32552.8 32930.3 33011.0 33304.8 60151.7 60856.0 60924.4 60948.4 61113.0 298858.0 300156.0 300530.0 300872.0 301802.0 39594.8 44296.3 45375.3 45775.5 46719.8 53403.0 53629.0 54020.5 54334.2 54508.2 44517.3 45881.3 46582.8 46631.0 46892.8 43603.3 43703.6 44298.6 45326.5 45859.5 40254.8 40890.6 41454.8 44300.6 44689.6 34142.7 36183.3 36690.6 37084.4 37126.0 27749.7 27869.0 27960.4 28204.0 28234.6 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | [#2] jquery.html Cycles:180 Average:26288.38 Median:304322.00 stddev:56407.09 (18.5%) stddev-sans-first:56531.88 08:21:37 INFO - PROCESS | 4199 | Values: 420.3 429.2 435.3 437.1 445.4 19629.0 20796.5 21829.9 21989.3 22047.4 22769.5 22957.0 22971.9 23395.9 23453.3 6125.3 6135.4 6344.5 6352.3 6352.4 6079.2 6088.1 6118.7 6129.3 6161.5 5920.5 6074.7 6080.4 6081.1 6136.8 6143.1 6186.9 6193.6 6194.5 6203.1 5513.4 5526.4 5561.9 5567.8 5568.5 26716.0 26893.3 26922.0 26985.3 27115.3 2881.5 2985.5 3031.5 3072.4 3089.9 15354.8 16026.0 16278.2 16508.0 16530.6 2089.8 2118.6 2123.4 2168.4 2170.7 89177.0 91560.0 91654.5 93155.0 93624.0 11737.5 12019.1 12109.7 12171.2 12392.3 17165.6 17183.9 17185.2 17188.7 17200.6 9041.6 9476.0 9495.2 9532.5 9545.3 9374.6 9406.0 9439.6 9567.0 9569.4 5346.2 5412.7 5413.4 5438.8 5585.1 2071.8 2119.6 2171.9 2173.8 2178.9 901.3 914.1 916.5 928.7 939.8 21184.9 21286.1 21547.5 21769.5 21770.2 907.9 911.3 922.4 939.1 944.8 11312.6 11713.4 11794.9 11857.6 12043.8 302999.0 304313.0 304331.0 304974.0 405993.0 111473.0 112726.4 113314.0 114123.0 114681.4 1025.2 1027.5 1049.9 1070.1 1070.4 18958.4 19374.0 19445.5 19531.0 19581.0 37363.4 41471.6 41988.4 43101.6 46017.0 63623.4 63955.7 64330.7 64416.4 64495.7 6433.8 8736.7 8768.3 9010.6 9174.4 9718.1 12696.6 12746.6 12758.3 13687.5 12206.3 12440.3 12504.8 12661.6 12764.7 11094.2 11506.8 11751.6 12096.4 12141.9 12109.2 12176.8 12412.7 12897.6 12976.5 8912.3 9050.5 9055.7 9113.5 9147.5 5863.6 8940.0 9657.3 11684.0 11890.0 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | [#3] mootools.html Cycles:180 Average:5538.06 Median:5080.53 stddev:2968.04 (58.4%) stddev-sans-first:2959.81 08:21:37 INFO - PROCESS | 4199 | Values: 1366.8 1461.0 1489.8 1502.4 1506.4 7587.8 8268.7 8440.1 8506.1 8770.2 8605.7 8690.5 8695.4 8701.1 8706.6 2115.2 2382.3 2417.9 2420.1 2423.3 2202.0 2351.9 2426.9 2428.8 2453.3 2392.5 2397.7 2414.2 2414.7 2422.8 2281.2 2288.7 2292.5 2299.5 2300.0 2287.4 2383.3 2387.8 2392.9 2393.5 8621.4 8680.9 8704.2 8710.0 8713.0 5038.1 5190.3 5193.4 5196.0 5201.5 3126.9 3274.5 3295.2 3321.5 3397.6 1294.5 1331.2 1334.5 1337.1 1390.4 10894.1 10957.7 10996.9 11027.3 11059.6 4226.8 4441.9 4442.3 4506.6 4520.6 9321.0 9323.3 9331.8 9334.9 9350.5 5069.6 5070.6 5075.1 5080.5 5080.5 4865.3 4918.3 4986.7 5014.0 5055.3 3882.8 3951.6 3953.0 3988.0 4020.9 1224.5 1231.6 1235.1 1238.0 1241.3 993.0 1008.9 1025.4 1026.9 1028.0 5759.6 5775.0 5810.6 5883.7 5909.1 1040.0 1063.3 1064.7 1074.5 1104.8 3760.5 3919.4 4197.8 4287.3 4410.5 9420.2 9570.3 9596.8 9598.9 9607.2 9482.8 9508.8 9534.0 9536.6 9547.0 7594.7 7960.2 7983.5 8206.4 8209.2 5787.3 6035.3 6085.8 6161.9 6197.3 9394.2 9556.0 9558.0 9591.1 9699.8 8419.4 9401.5 9532.5 9637.5 9685.3 6737.5 7416.9 7575.6 7611.0 7793.6 8313.8 8333.5 8424.1 8449.1 8516.9 7078.9 7141.9 7181.8 7208.7 7225.9 7210.5 7265.1 7307.1 7325.5 7413.6 6100.5 6337.7 6362.9 6419.2 6428.6 4845.3 4857.2 4932.8 4959.3 4965.2 4990.3 5062.0 5081.4 5097.5 5098.2 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | [#4] prototype.html Cycles:180 Average:4757.98 Median:5362.86 stddev:1362.13 (25.4%) stddev-sans-first:1326.31 08:21:37 INFO - PROCESS | 4199 | Values: 411.9 413.2 414.9 419.6 424.5 5357.8 5363.5 5377.5 5418.2 5440.1 5174.8 5198.3 5331.1 5356.9 5367.5 4276.1 4291.2 4300.7 4310.9 4318.9 4291.8 4293.4 4304.7 4311.4 4313.9 4138.6 4248.5 4290.7 4304.0 4309.6 4260.5 4270.0 4270.8 4287.1 4301.7 3905.8 3908.9 3916.8 3923.5 3926.8 4972.2 5182.2 5212.4 5247.4 5282.2 4865.8 4879.8 4887.2 4894.8 4898.0 5652.7 5680.9 5688.8 5697.4 5714.5 4067.0 4104.9 4147.6 4166.9 4187.6 5488.9 5678.0 5777.0 5826.9 5829.7 4168.0 4236.6 4250.8 4258.3 4261.5 4283.6 4283.6 4293.5 4313.2 4324.7 4613.0 4697.4 4710.6 4841.2 4841.9 5628.4 5648.2 5650.5 5651.5 5658.7 1401.4 1425.4 1428.4 1439.0 1441.0 996.7 997.7 998.6 1004.2 1016.3 5309.9 5345.2 5355.5 5403.6 5409.5 5549.6 5594.7 5622.2 5638.9 5667.9 5113.5 5293.2 5347.2 5350.5 5362.2 2923.4 2950.7 2957.7 2967.0 2975.5 5586.6 5750.8 5767.8 5801.3 5951.4 5849.2 5861.2 5866.7 5908.4 5916.6 5993.0 5994.6 5997.6 5998.3 5999.4 5591.7 5755.7 5762.5 5771.9 5779.7 5458.8 5600.7 5723.4 5731.9 5732.7 5844.5 5980.5 5981.2 5984.4 5990.4 4261.6 4269.7 4271.5 4274.6 4280.3 5706.0 5721.8 5738.9 5779.4 5831.6 5609.5 5618.2 5620.9 5633.8 5641.8 5404.6 5632.5 5636.4 5642.9 5645.0 5572.6 5576.7 5610.4 5611.7 5632.5 5633.6 5634.7 5647.4 5662.9 5663.0 5325.6 5458.9 5511.7 5513.9 5547.6 08:21:37 INFO - PROCESS | 4199 | 08:21:37 INFO - PROCESS | 4199 | [#5] yui.html Cycles:180 Average:18142.20 Median:45055.21 stddev:22128.42 (49.1%) stddev-sans-first:22152.27 08:21:37 INFO - PROCESS | 4199 | Values: 820.5 831.1 844.6 909.2 919.8 15619.1 16731.0 16744.5 16757.3 16757.7 16313.3 16481.9 16505.8 16510.1 16515.8 689.1 721.5 795.5 834.3 836.3 837.4 838.5 839.0 839.2 839.3 884.9 886.0 886.6 886.7 887.4 717.3 750.3 822.6 859.0 877.9 636.8 637.0 637.3 637.4 637.5 15780.6 15819.2 15836.8 15847.3 16079.2 15145.3 15867.7 16055.1 16139.9 16420.2 12819.6 12853.5 12886.9 12935.4 13151.2 3965.7 4024.6 4028.5 4055.5 4068.0 78597.6 79757.6 79793.6 80060.6 80191.6 12258.6 12564.0 12649.6 12672.6 12840.4 22312.3 22556.2 22644.3 22881.0 23078.0 9094.6 9155.0 9207.9 9286.5 9400.8 4755.3 4807.4 4846.8 4909.0 4983.2 3690.6 3720.4 3752.3 3772.8 3787.3 650.2 661.3 668.4 669.8 676.8 694.6 696.0 705.5 712.1 739.8 11340.9 11496.8 11553.7 11737.4 12034.8 658.1 658.8 670.6 672.5 684.2 5263.7 5558.6 5599.8 5661.1 5681.5 78464.6 78552.6 80574.2 80779.6 82236.0 55127.0 59704.4 61500.0 68480.7 68667.7 59405.7 60927.7 61919.7 61928.0 63424.0 10325.8 10654.1 10676.3 10786.1 10807.4 44997.1 45113.3 45764.0 46003.5 46255.8 51197.0 54773.7 55484.0 56848.0 58068.0 12534.0 12857.7 12922.6 12934.6 13069.3 13794.1 14018.6 14186.3 14326.4 15550.6 15065.2 15631.4 15837.9 16584.6 16742.9 15112.6 15435.1 15454.9 15613.4 16266.2 14480.9 14551.6 15330.3 15339.2 15693.7 11735.9 11753.9 12104.1 12181.2 13390.7 12379.3 12453.2 12579.5 12616.9 12688.9 08:21:37 INFO - PROCESS | 4199 | -------- Summary: end -------- 08:21:37 INFO - PROCESS | 4199 | 08:21:38 INFO - TEST-INFO | 4199: exit 0 08:21:38 INFO - TEST-OK | dromaeo_css | took 1113428ms 08:21:38 INFO - TEST-START | kraken 08:21:38 INFO - operating with platform_type : linux_ 08:21:38 INFO - Initialising browser for kraken test... 08:21:38 INFO - TEST-INFO | started process 4514 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzFUA5u/profile http://localhost:33870/getInfo.html) 08:21:42 INFO - PROCESS | 4514 | __metrics Screen width/height:1600/1200 08:21:42 INFO - PROCESS | 4514 | colorDepth:24 08:21:42 INFO - PROCESS | 4514 | Browser inner width/height: 1024/697 08:21:42 INFO - PROCESS | 4514 | __metrics 08:21:42 INFO - PROCESS | 4514 | 1486138902490 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:21:42 INFO - PROCESS | 4514 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:21:42 INFO - PROCESS | 4514 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:21:43 INFO - PROCESS | 4514 | console.error: PushService: 08:21:43 INFO - PROCESS | 4514 | stateChangeProcessEnqueue: Error transitioning state 08:21:43 INFO - PROCESS | 4514 | UnknownError 08:21:43 INFO - TEST-INFO | 4514: exit 0 08:21:43 INFO - Browser initialized. 08:21:43 INFO - Running cycle 1/1 for kraken test... 08:21:43 INFO - TEST-INFO | started process 4610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzFUA5u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:55 INFO - PROCESS | 4610 | RSS: Main: 138301440 08:21:55 INFO - PROCESS | 4610 | 08:21:58 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33870/tests/kraken/driver.html?testName=audio-beat-detection) 08:21:58 INFO - PROCESS | 4610 | RSS: Main: 182075392 08:21:58 INFO - PROCESS | 4610 | 08:22:01 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33870/tests/kraken/driver.html?testName=audio-dft) 08:22:02 INFO - PROCESS | 4610 | RSS: Main: 172056576 08:22:02 INFO - PROCESS | 4610 | 08:22:04 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33870/tests/kraken/driver.html?testName=audio-fft) 08:22:05 INFO - PROCESS | 4610 | RSS: Main: 263856128 08:22:05 INFO - PROCESS | 4610 | 08:22:07 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33870/tests/kraken/driver.html?testName=audio-oscillator) 08:22:08 INFO - PROCESS | 4610 | RSS: Main: 170426368 08:22:08 INFO - PROCESS | 4610 | 08:22:10 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33870/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:10 INFO - PROCESS | 4610 | RSS: Main: 172838912 08:22:10 INFO - PROCESS | 4610 | 08:22:13 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33870/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:14 INFO - PROCESS | 4610 | RSS: Main: 211525632 08:22:14 INFO - PROCESS | 4610 | 08:22:17 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33870/tests/kraken/driver.html?testName=imaging-desaturate) 08:22:18 INFO - PROCESS | 4610 | RSS: Main: 209375232 08:22:18 INFO - PROCESS | 4610 | 08:22:21 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33870/tests/kraken/driver.html?testName=json-parse-financial) 08:22:21 INFO - PROCESS | 4610 | RSS: Main: 208437248 08:22:21 INFO - PROCESS | 4610 | 08:22:23 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33870/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:22:23 INFO - PROCESS | 4610 | RSS: Main: 170901504 08:22:23 INFO - PROCESS | 4610 | 08:22:25 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:26 INFO - PROCESS | 4610 | RSS: Main: 178159616 08:22:26 INFO - PROCESS | 4610 | 08:22:28 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:28 INFO - PROCESS | 4610 | RSS: Main: 187301888 08:22:28 INFO - PROCESS | 4610 | 08:22:31 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:22:31 INFO - PROCESS | 4610 | RSS: Main: 197730304 08:22:31 INFO - PROCESS | 4610 | 08:22:34 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:22:35 INFO - PROCESS | 4610 | RSS: Main: 192655360 08:22:35 INFO - PROCESS | 4610 | 08:22:36 INFO - PROCESS | 4610 | Cycle 1(1): loaded http://localhost:33870/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33870/tests/kraken/driver.html?testName=ai-astar) 08:22:37 INFO - PROCESS | 4610 | RSS: Main: 244903936 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | __start_tp_report 08:22:37 INFO - PROCESS | 4610 | _x_x_mozilla_page_load 08:22:37 INFO - PROCESS | 4610 | _x_x_mozilla_page_load_details 08:22:37 INFO - PROCESS | 4610 | |i|pagename|runs| 08:22:37 INFO - PROCESS | 4610 | |0;ai-astar;105;114;114;113;114;113;112;113;114;113 08:22:37 INFO - PROCESS | 4610 | |1;audio-beat-detection;148;120;118;172;119;122;119;120;137;119 08:22:37 INFO - PROCESS | 4610 | |2;audio-dft;161;161;162;161;161;162;163;161;160;160 08:22:37 INFO - PROCESS | 4610 | |3;audio-fft;75;76;76;114;76;77;74;77;102;76 08:22:37 INFO - PROCESS | 4610 | |4;audio-oscillator;82;82;110;83;83;83;93;82;83;112 08:22:37 INFO - PROCESS | 4610 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;117;117;116 08:22:37 INFO - PROCESS | 4610 | |6;imaging-darkroom;147;146;147;147;149;147;146;147;147;146 08:22:37 INFO - PROCESS | 4610 | |7;imaging-desaturate;95;95;97;96;95;96;96;95;95;95 08:22:37 INFO - PROCESS | 4610 | |8;json-parse-financial;50;50;49;50;50;49;49;50;49;48 08:22:37 INFO - PROCESS | 4610 | |9;json-stringify-tinderbox;63;65;65;64;64;64;67;65;65;64 08:22:37 INFO - PROCESS | 4610 | |10;stanford-crypto-aes;77;77;80;79;84;81;77;76;76;77 08:22:37 INFO - PROCESS | 4610 | |11;stanford-crypto-ccm;121;119;122;121;123;123;119;119;169;135 08:22:37 INFO - PROCESS | 4610 | |12;stanford-crypto-pbkdf2;162;159;157;158;160;158;163;155;171;158 08:22:37 INFO - PROCESS | 4610 | |13;stanford-crypto-sha256-iterative;62;60;61;61;62;60;61;60;60;60 08:22:37 INFO - PROCESS | 4610 | __end_tp_report 08:22:37 INFO - PROCESS | 4610 | __start_cc_report 08:22:37 INFO - PROCESS | 4610 | _x_x_mozilla_cycle_collect,1539 08:22:37 INFO - PROCESS | 4610 | __end_cc_report 08:22:37 INFO - PROCESS | 4610 | __startTimestamp1486138957175__endTimestamp 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | ------- Summary: start ------- 08:22:37 INFO - PROCESS | 4610 | Number of tests: 14 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:2.72 (2.4%) stddev-sans-first:0.71 08:22:37 INFO - PROCESS | 4610 | Values: 105.0 114.0 114.0 113.0 114.0 113.0 112.0 113.0 114.0 113.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:121.00 stddev:17.96 (14.8%) stddev-sans-first:17.75 08:22:37 INFO - PROCESS | 4610 | Values: 148.0 120.0 118.0 172.0 119.0 122.0 119.0 120.0 137.0 119.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 08:22:37 INFO - PROCESS | 4610 | Values: 161.0 161.0 162.0 161.0 161.0 162.0 163.0 161.0 160.0 160.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#3] audio-fft Cycles:10 Average:82.30 Median:76.00 stddev:13.86 (18.2%) stddev-sans-first:14.45 08:22:37 INFO - PROCESS | 4610 | Values: 75.0 76.0 76.0 114.0 76.0 77.0 74.0 77.0 102.0 76.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:83.00 stddev:11.91 (14.3%) stddev-sans-first:12.33 08:22:37 INFO - PROCESS | 4610 | Values: 82.0 82.0 110.0 83.0 83.0 83.0 93.0 82.0 83.0 112.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:22:37 INFO - PROCESS | 4610 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 08:22:37 INFO - PROCESS | 4610 | Values: 147.0 146.0 147.0 147.0 149.0 147.0 146.0 147.0 147.0 146.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 08:22:37 INFO - PROCESS | 4610 | Values: 95.0 95.0 97.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:50.00 stddev:0.70 (1.4%) stddev-sans-first:0.71 08:22:37 INFO - PROCESS | 4610 | Values: 50.0 50.0 49.0 50.0 50.0 49.0 49.0 50.0 49.0 48.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:1.07 (1.7%) stddev-sans-first:0.97 08:22:37 INFO - PROCESS | 4610 | Values: 63.0 65.0 65.0 64.0 64.0 64.0 67.0 65.0 65.0 64.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:2.59 (3.3%) stddev-sans-first:2.70 08:22:37 INFO - PROCESS | 4610 | Values: 77.0 77.0 80.0 79.0 84.0 81.0 77.0 76.0 76.0 77.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:122.50 stddev:15.45 (12.6%) stddev-sans-first:16.23 08:22:37 INFO - PROCESS | 4610 | Values: 121.0 119.0 122.0 121.0 123.0 123.0 119.0 119.0 169.0 135.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.10 Median:159.50 stddev:4.48 (2.8%) stddev-sans-first:4.70 08:22:37 INFO - PROCESS | 4610 | Values: 162.0 159.0 157.0 158.0 160.0 158.0 163.0 155.0 171.0 158.0 08:22:37 INFO - PROCESS | 4610 | 08:22:37 INFO - PROCESS | 4610 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:61.00 stddev:0.82 (1.3%) stddev-sans-first:0.73 08:22:37 INFO - PROCESS | 4610 | Values: 62.0 60.0 61.0 61.0 62.0 60.0 61.0 60.0 60.0 60.0 08:22:37 INFO - PROCESS | 4610 | -------- Summary: end -------- 08:22:37 INFO - PROCESS | 4610 | 08:22:38 INFO - TEST-INFO | 4610: exit 0 08:22:38 INFO - TEST-OK | kraken | took 60186ms 08:22:38 INFO - SUITE-END | took 1173s 08:22:38 INFO - Completed test suite (00:19:33) 08:22:38 INFO - javascript benchmark 08:22:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3158.0841555948778, 3289.079057863799, 3293.7035666789984, 3313.1717070732316, 3330.050935483386, 5484.670919744818, 5554.805581804632, 5561.888762224763, 5562.888742225153, 5567.415421380758, 5512.972435137829, 5565.888682226353, 5565.9443405565935, 5578.581606379517, 5615.241942337783, 4965.453800081988, 4982.800687972477, 5013.874653133665, 5064.113780088488, 5069.340985671876, 4917.3607431033915, 5024.296598476216, 5072.036313100518, 5072.923906141393, 5078.974605126969, 4912.484189160123, 4919.065377578267, 4929.852104436873, 4940.629452791037, 5006.599472042246, 4999.250112483121, 5014.172661510846, 5020.497950204986, 5028.522290382408, 5041.59667226621, 4902.460729319772, 4944.258361245806, 4983.127952608315, 5006.69959802413, 5020.648554601179, 5329.360476742777, 5384.6769193848495, 5394.541463975567, 5431.158170483542, 5447.5641948644015, 5914.467697907209, 5915.674637894914, 5923.08192230205, 5931.525477961753, 5953.791617293417, 5911.970440147815, 5956.344802071769, 5977.103434484862, 5978.432048955342, 5980.461758441717, 5348.224507446437, 5389.461053894579, 5481.342238931354, 5522.6134170607675, 5569.944300556983, 6008.188894499274, 6015.969920150371, 6035.698215089228, 6039.094135879565, 6059.091136329498, 5792.333881603585, 5829.329627092854, 5833.562482813721, 5837.357890632027, 5839.357670656224, 5748.798792042258, 5770.019096753478, 5833.7083145842535, 5864.824055278348, 5929.881402371929, 5830.0, 5864.06175011996, 5908.793192238251, 5942.1978032965, 5958.582899197101, 5926.674032928144, 5928.79249226275, 5936.7031648417405, 5938.703064846741, 5938.732757025944, 4467.106578684276, 4500.144972455223, 4575.10785396851, 4587.655925805578, 4604.723716577016, 3964.960350396459, 4046.4537287466405, 4054.2094291613444, 4058.3912413138264, 4101.91796164075, 5707.200991861143, 5710.71446427677, 5714.200011998323, 5714.714264286769, 5737.311522617312, 5742.253507044141, 5853.356130825606, 5897.734601942923, 5976.8804623908145, 5994.940050599438, 5676.659400435987, 5748.45389687979, 5749.597528172988, 5763.625364351304, 5783.624064435882, 5087.567556757717, 5088.567471764942, 5090.618203634742, 5094.745262736848, 5122.078025955289, 5928.288605367384, 5953.523718102455, 6109.480694140959, 6172.444479996732, 6174.598651087665, 5866.442687944638, 5889.646621202742, 5910.143029260647, 5970.522358211246, 6010.278766548042, 6138.846528836815, 6141.324454310112, 6147.877042459215, 6148.292946311052, 6148.9692551536955, 5722.284714410699, 5812.9709351452975, 5874.324452687824, 5879.647221166743, 5883.558733094949, 5875.118732190205, 5877.647341159544, 5923.763049477972, 5926.407359264039, 6009.849753756191, 5773.566982476415, 5780.508656764222, 5788.855278618068, 5827.70861456917, 5830.5627077968475, 5771.884562308815, 5779.537636989115, 5784.768609255751, 5786.392428794916, 5790.536757059341, 5592.496675299242, 5605.607607467438, 5675.177099320493, 5730.914036289376, 5744.425557444222, 5824.126381042708, 5830.854228644148, 5831.970840145772, 5833.154192642128, 5852.297724272944, 5672.9221447924765, 5695.544356451391, 5705.572082093942, 5708.828735137952, 5712.37163911961], "value": 5455.26734454915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.635741304272, 608.0544752909226, 619.4177473175215, 637.9362063793619, 668.9096971908797, 37057.81471092644, 48037.0, 49836.0, 49836.0, 49895.00209995802, 52626.0, 53181.0, 53181.46818531813, 53267.0, 53267.73366133174, 8787.736367908972, 9395.906040939606, 9587.952060239724, 9590.568424420882, 9593.376430532027, 8779.429337093101, 11269.718257043558, 11442.0, 11550.30698158109, 11906.226095303777, 11647.883521164807, 11688.0, 11701.824472632918, 11728.882711172908, 11737.471813768358, 11628.941855290712, 11687.883121168765, 11698.590549330733, 11710.590129345517, 11710.64868053964, 11443.656690299222, 11507.309561426258, 11526.827097593543, 11530.654080377519, 11535.826962595567, 48244.51755482436, 48368.51631483711, 48376.758116209545, 48420.75789621029, 48861.0, 4537.38745269391, 4540.205464043779, 4620.168369693454, 4674.298855171717, 4704.988427488072, 34957.0, 35028.0, 35058.0, 35242.0, 36478.63521364806, 4692.906141877177, 4721.268203428474, 4727.267273572602, 4735.526447355272, 4764.737939413331, 404747.0, 405017.0, 405561.0, 406393.99999999703, 406972.0, 72345.6382718083, 72821.6358918202, 72853.0, 72881.0, 72904.0, 77588.61205693935, 77700.0, 77767.61116194383, 77798.6110069446, 78007.21992780112, 19077.713834292495, 19689.113989870202, 19701.226889580008, 19711.11299991504, 19729.704054439197, 14256.0, 14509.85490145106, 14796.408143674133, 15395.615109622126, 15553.144577048144, 20545.280915167896, 21969.681819090907, 22127.889360553418, 22168.0, 22215.777842221694, 7484.176740558534, 7550.7734767957045, 7685.193054729285, 7728.304452599293, 7732.149463559004, 1004.2618675273668, 1011.4538149399389, 1016.5222345497605, 1019.3221507697447, 1025.4821315235758, 35000.64999350025, 35288.82355588257, 35329.1167720809, 35463.645363546035, 35724.28551428957, 1091.6233899304727, 1157.288267715349, 1171.800793865045, 1173.436750359832, 1209.3227792436264, 18528.351507697167, 18567.25730970746, 18680.045546533496, 18800.811991879906, 18870.245190192512, 503950.0, 508601.0, 509238.0, 509940.0, 510045.0, 70995.0, 71342.28657713356, 71449.0, 71697.0, 71784.0, 1565.725997950377, 1601.0, 1607.252627528205, 1611.4923799003511, 1622.0348892408997, 31214.0, 32552.837235814615, 32930.341393171526, 33011.0, 33304.83347583246, 60151.69924150351, 60856.0, 60924.39075609364, 60948.39051609604, 61113.0, 298858.0, 300156.0, 300530.0, 300872.0, 301802.0, 39594.80202598969, 44296.33555496734, 45375.319370210134, 45775.542244577126, 46719.76640116778, 53403.0, 53629.0, 54020.45979540311, 54334.18498722601, 54508.18237726516, 44517.33224001707, 45881.311780322656, 46582.76708616571, 46631.0, 46892.7655361721, 43603.345949810144, 43703.56296436995, 44298.55701442944, 45326.54673453223, 45859.54140458686, 40254.798726006185, 40890.59109408987, 41454.792726036176, 44300.556994429644, 44689.55310446854, 34142.658573413944, 36183.27633447369, 36690.63309366872, 37084.44373334348, 37126.0, 27749.722502775523, 27869.0, 27960.440791183657, 28204.0, 28234.576481352386], "value": 22644.43637004208, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.2753960565444, 429.22524842659027, 435.3100335967491, 437.14101789982686, 445.41205608596664, 19629.0, 20796.480087997807, 21829.890850545766, 21989.34031979041, 22047.448813779625, 22769.54460910781, 22957.0, 22971.885140574275, 23395.88302058487, 23453.296401107953, 6125.264968203822, 6135.417135372132, 6344.524160687944, 6352.269489008754, 6352.364763523634, 6079.240094988127, 6088.1476593276975, 6118.694065296718, 6129.264488261415, 6161.537884658668, 5920.4671579557835, 6074.696265186745, 6080.422359875804, 6081.148639190517, 6136.815895523141, 6143.139960405524, 6186.876262474748, 6193.566450348478, 6194.50443964484, 6203.100550420164, 5513.42109078549, 5526.364468086141, 5561.888762224733, 5567.777288908439, 5568.52667523257, 26716.0, 26893.327666808487, 26922.0, 26985.32536686579, 27115.322116947038, 2881.5245484495035, 2985.537241727514, 3031.5301128325145, 3072.3548054908497, 3089.90730278092, 15354.769678454832, 16026.0, 16278.186090695537, 16508.0, 16530.58673533159, 2089.8328133749264, 2118.555103428282, 2123.3948324727535, 2168.4036889855256, 2170.739511258659, 89177.0, 91559.99999999933, 91654.54172729094, 93155.0, 93624.0, 11737.530498780125, 12019.098567607465, 12109.69725756863, 12171.208871423507, 12392.256464611972, 17165.57086072833, 17183.914080429517, 17185.226664800113, 17188.65622687539, 17200.569985750455, 9041.593128309241, 9476.0, 9495.240380769523, 9532.475713835667, 9545.284103692251, 9374.62501499946, 9405.952970235105, 9439.575219115157, 9567.043295670377, 9569.3779904306, 5346.224797404355, 5412.675239485643, 5413.350397952271, 5438.809641662523, 5585.078462053716, 2071.78246284138, 2119.629064913634, 2171.8805465699224, 2173.815225705801, 2178.880161591096, 901.3195037746459, 914.07221670005, 916.5188276154938, 928.661038720862, 939.793245485992, 21184.89407552983, 21286.148554057974, 21547.461313467287, 21769.455763606038, 21770.238041668465, 907.9092090790868, 911.2618778789202, 922.3820040572832, 939.0984654731398, 944.7874228298577, 11312.6040588579, 11713.355765432812, 11794.88205117955, 11857.644270671894, 12043.819342709867, 302999.0, 304313.0, 304331.0, 304974.0, 405993.0, 111473.0, 112726.43636781763, 113314.0, 114123.0, 114681.4265928665, 1025.2259544044348, 1027.4554486122368, 1049.92125590581, 1070.1331921143899, 1070.400575677623, 18958.43124706261, 19374.0, 19445.51386215356, 19531.0, 19581.0, 37363.439548406255, 41471.58528414677, 41988.3701744468, 43101.56898431101, 46017.0, 63623.3637663636, 63955.680221598595, 64330.678346607965, 64416.355836441035, 64495.677521612095, 6433.806985790434, 8736.733173689652, 8768.25469835071, 9010.5945232466, 9174.394480965937, 9718.076782705632, 12696.555620553605, 12746.617601471971, 12758.298293593942, 13687.45250190021, 12206.267623942593, 12440.31578263168, 12504.812427813407, 12661.620151395473, 12764.723527647533, 11094.167937404889, 11506.82739758921, 11751.647450576496, 12096.395180241128, 12141.878581214429, 12109.212901161396, 12176.75646487083, 12412.749305479392, 12897.613071607866, 12976.545820896223, 8912.28701703875, 9050.502222377832, 9055.728328150166, 9113.498757568132, 9147.496887671243, 5863.560232982458, 8940.0, 9657.3239873211, 11684.0, 11890.0], "value": 9892.379813222966, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.7608168570498, 1460.97808532872, 1489.7616381378975, 1502.4215676964373, 1506.4275575281392, 7587.810304742384, 8268.669253229871, 8440.071592124868, 8506.106858779833, 8770.166834150761, 8605.741827745178, 8690.522021288827, 8695.434796738207, 8701.129887011311, 8706.564671766402, 2115.23851413491, 2382.321038504031, 2417.9395515112087, 2420.0561780905373, 2423.2851308863837, 2202.0, 2351.870647114408, 2426.915057972971, 2428.781409673129, 2453.3130723397476, 2392.545416370893, 2397.7242617098996, 2414.239514552919, 2414.7464516225805, 2422.806175505964, 2281.235786011688, 2288.7482376938524, 2292.541491701658, 2299.482616411294, 2299.9655005174936, 2287.3709729824263, 2383.285014495644, 2387.8209134315, 2392.8923198455936, 2393.497365553236, 8621.396502244845, 8680.913190868137, 8704.216620504121, 8709.95645021777, 8713.0, 5038.093143234217, 5190.273361729361, 5193.402758682762, 5196.0, 5201.505856943584, 3126.937461250762, 3274.4760838266, 3295.1597342677705, 3321.5183798349344, 3397.6432474590306, 1294.5080869269711, 1331.2478449675903, 1334.4795529743408, 1337.0840973932886, 1390.388229179168, 10894.074003709695, 10957.726056848642, 10996.945015274872, 11027.338359698444, 11059.612913548068, 4226.788660566959, 4441.933370999438, 4442.3336499524685, 4506.571875671806, 4520.59314661682, 9321.021292764297, 9323.25413966881, 9331.766705832408, 9334.859977100348, 9350.485723285148, 5069.6451248413, 5070.569001634903, 5075.061113693979, 5080.517350851663, 5080.54275115234, 4865.343178670834, 4918.3360246367, 4986.725730084808, 5014.047331007097, 5055.292259083732, 3882.8446862125197, 3951.6245956634075, 3953.0, 3987.980060099681, 4020.8994775130273, 1224.5040758492826, 1231.587418214908, 1235.110720281396, 1238.0343332200898, 1241.2986662535639, 992.9553170107218, 1008.8839783424853, 1025.4411345816548, 1026.8767747870304, 1027.9897201028043, 5759.625624334405, 5775.0, 5810.564207684441, 5883.7058147092475, 5909.113632955005, 1039.9720544937402, 1063.2716589136467, 1064.7497838008062, 1074.5460117241057, 1104.8232282834708, 3760.511133552621, 3919.412088186795, 4197.8320867164975, 4287.2711639021445, 4410.4927933287445, 9420.152186303265, 9570.33007689469, 9596.760080997892, 9598.9040109598, 9607.183389411979, 9482.810343793086, 9508.809823803485, 9534.0, 9536.570854311572, 9547.045295470536, 7594.696212151563, 7960.2039796019935, 7983.520988740578, 8206.384521160817, 8209.220124088202, 5787.305523337226, 6035.275766907999, 6085.817425477243, 6161.876762464815, 6197.349278325711, 9394.201492873182, 9556.044395560388, 9558.0, 9591.088846559845, 9699.806003880023, 8419.36854735885, 9401.482918439551, 9532.52337383142, 9637.469939153414, 9685.32202745801, 6737.461003119641, 7416.851662966603, 7575.583342916192, 7610.96194519046, 7793.64928578204, 8313.750587482386, 8333.458325209085, 8424.073351931405, 8449.070602233627, 8516.872246916426, 7078.929210707827, 7141.892871606826, 7181.820454488679, 7208.747693830901, 7225.891611625935, 7210.531315464478, 7265.12818461788, 7307.050083489113, 7325.523840950321, 7413.555186688816, 6100.542459315658, 6337.683115844097, 6362.904556431749, 6419.2296924369375, 6428.614283143026, 4845.3216549682365, 4857.247126695368, 4932.827351042696, 4959.330490383896, 4965.1807451770055, 4990.251462280687, 5061.974690126526, 5081.364829396325, 5097.490250974873, 5098.1842905135], "value": 4539.622017193121, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.90114372550585, 413.1942711712162, 414.8879802453338, 419.61240541319904, 424.535134028239, 5357.812476563321, 5363.517283444489, 5377.542908852743, 5418.160185171295, 5440.129579267323, 5174.793008279665, 5198.298229738984, 5331.093714068617, 5356.91964620531, 5367.543758780508, 4276.144771045788, 4291.249031419505, 4300.677449191316, 4310.935335969963, 4318.870433886973, 4291.785410729467, 4293.355996600504, 4304.655627549789, 4311.353297005458, 4313.913721725564, 4138.6482149017365, 4248.532661407243, 4290.721103128302, 4304.010077682135, 4309.59058889405, 4260.531341552427, 4269.978650106761, 4270.825522981174, 4287.142571485684, 4301.720388174758, 3905.824237909301, 3908.9413658795143, 3916.784576848272, 3923.4899463069905, 3926.7840268785208, 4972.204447288416, 5182.196759502283, 5212.400573934008, 5247.370315562119, 5282.154855223184, 4865.781039853215, 4879.756012199411, 4887.169181239198, 4894.755262236874, 4898.02039592083, 5652.660840349592, 5680.943190568083, 5688.829335119953, 5697.401772813836, 5714.457126572969, 4067.0, 4104.91790164198, 4147.605977432139, 4166.895827604304, 4187.560306167869, 5488.945110548883, 5677.977963966526, 5777.0, 5826.941730582682, 5829.650220986754, 4168.0, 4236.576342365739, 4250.787460626956, 4258.254805409041, 4261.488621365394, 4283.5502272260965, 4283.629238643604, 4293.484781826139, 4313.180495705806, 4324.675649326313, 4612.97693511537, 4697.436307643105, 4710.62315014798, 4841.176999910023, 4841.9031619367415, 5628.437156284339, 5648.209250704905, 5650.485533937441, 5651.519620832275, 5658.7170641467765, 1401.3833913078213, 1425.3799597175262, 1428.3929330034694, 1438.9712205755827, 1440.9567712968628, 996.7059717383389, 997.6558087459815, 998.557085012165, 1004.1724062206714, 1016.3139880580578, 5309.867253318621, 5345.224942383333, 5355.544778693777, 5403.621746477709, 5409.459054094559, 5549.556035517149, 5594.692291923983, 5622.212890195376, 5638.915416268759, 5667.858303542444, 5113.51421614946, 5293.206019097166, 5347.235412314005, 5350.545203657655, 5362.195670649356, 2923.444545536341, 2950.704929507032, 2957.659869115036, 2966.9703302967127, 2975.4941659917868, 5586.608937374426, 5750.84983003401, 5767.826965191051, 5801.274840644919, 5951.375105613848, 5849.151872978395, 5861.17943487912, 5866.67733274674, 5908.350081491032, 5916.645001299936, 5992.951233534027, 5994.610350327215, 5997.580169388101, 5998.310194327708, 5999.400059994053, 5591.692456914909, 5755.65466072037, 5762.510186634183, 5771.9134212986, 5779.711014449176, 5458.781648734006, 5600.747966341443, 5723.427657234243, 5731.8853622928145, 5732.71336433185, 5844.503217226584, 5980.518084994678, 5981.162637230878, 5984.444044548536, 5990.400959903975, 4261.616454519108, 4269.701120921506, 4271.530131685574, 4274.551172126882, 4280.336547835091, 5705.971470142623, 5721.799737009184, 5738.942610573841, 5779.439551321204, 5831.562632802472, 5609.49514543693, 5618.2134501170685, 5620.859478512906, 5633.788735421904, 5641.830745077655, 5404.595105083772, 5632.493075623213, 5636.408177141341, 5642.887142257214, 5644.971775141098, 5572.60991730575, 5576.665400076008, 5610.410906854885, 5611.691356975405, 5632.493075623213, 5633.605647604629, 5634.74643641029, 5647.435256474319, 5662.867426514631, 5662.971685141548, 5325.588718989394, 5458.8908221834545, 5511.669299842022, 5513.944860551504, 5547.583931205095], "value": 4344.421727873807, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.4953953318711, 831.1065604475191, 844.5692696724668, 909.1543468942212, 919.793046564523, 15619.140947247897, 16731.0, 16744.497665070037, 16757.329706811728, 16757.664846703057, 16313.347466101372, 16481.91759041206, 16505.83494165058, 16510.091944943055, 16515.83484165158, 689.0731965506399, 721.5418209436992, 795.5306369242146, 834.3200291762195, 836.3392919593514, 837.4347315561971, 838.4591938199858, 838.9580520973957, 839.236294971576, 839.2782207301716, 884.8849649545582, 885.9822803543925, 886.5522910929972, 886.7118186589352, 887.40987243483, 717.347214035228, 750.3284560318511, 822.6257053040852, 858.9613467393982, 877.855153899609, 636.8280564247674, 636.9904451433232, 637.2607774981, 637.4199478474612, 637.5473413876153, 15780.60548486297, 15819.209039547977, 15836.76244856328, 15847.286872090783, 16079.196040197943, 15145.320504810696, 15867.682646347124, 16055.116968566725, 16139.919300403422, 16420.1789910504, 12819.615411537668, 12853.485860565566, 12886.87113128866, 12935.353232338346, 13151.21092734439, 3965.7422267552515, 4024.557298697141, 4028.4561584186054, 4055.5336136344404, 4067.959320406788, 78597.60701196457, 79757.60121199414, 79793.60103199446, 80060.59969700173, 80191.59904200441, 12258.632241032783, 12564.0, 12649.620511384674, 12672.619821405373, 12840.357982101044, 22312.330630081124, 22556.210532631278, 22644.320670379915, 22881.0, 23078.0, 9094.636214551476, 9155.0, 9207.907920920705, 9286.535673216313, 9400.764980875396, 4755.286706993978, 4807.399075115611, 4846.75766211688, 4909.042736666316, 4983.20464380369, 3690.6493883081066, 3720.4419337099653, 3752.3433399155037, 3772.773633581939, 3787.2614840106467, 650.2197363164222, 661.3419647450817, 668.4017804065431, 669.7990602819135, 676.7800464848943, 694.6422592364936, 695.9756408525677, 705.5202462325668, 712.0956385390525, 739.840934199139, 11340.886591134147, 11496.77006459866, 11553.711157221136, 11737.413129343498, 12034.759304813855, 658.0984051848902, 658.8385845467795, 670.6345041952147, 672.495628278791, 684.1926526698544, 5263.6841789492755, 5558.610897237155, 5599.776008959596, 5661.094224923992, 5681.488666020078, 78464.60767696126, 78552.60723696345, 80574.19425805785, 80779.5961020203, 82236.0, 55127.0, 59704.402955970756, 61500.0, 68480.6575967117, 68667.65666171638, 59405.70297148487, 60927.69536152291, 61919.690401547705, 61928.0, 63424.0, 10325.845112323472, 10654.094401975919, 10676.346924322736, 10786.137109031415, 10807.405592692163, 44997.10005799931, 45113.32330014987, 45764.0, 46003.53996459993, 46255.76872115618, 51197.0, 54773.72613136909, 55484.0, 56848.0, 58068.0, 12533.989511098072, 12857.742845143232, 12922.612321630366, 12934.611961641165, 13069.346532673519, 13794.103383280055, 14018.579442616738, 14186.290685465892, 14326.355314011103, 15550.61123471877, 15065.246737663292, 15631.374745010073, 15837.920810395874, 16584.585385365463, 16742.91628541898, 15112.622184445478, 15435.073895566302, 15454.922725386301, 15613.375464981273, 16266.186690665656, 14480.85519144795, 14551.636209094857, 15330.310136044129, 15339.227158106703, 15693.686126277182, 11735.941320293685, 11753.941230293794, 12104.071832949432, 12181.208221465577, 13390.732185356434, 12379.319137447526, 12453.247957870743, 12579.496820127093, 12616.936915315364, 12688.936555317534], "value": 7646.591298705372, "unit": "score"}], "name": "dromaeo_css", "value": 7543.819049704583, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 114.0, 113.0, 114.0, 113.0, 112.0, 113.0, 114.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 118.0, 172.0, 119.0, 122.0, 119.0, 120.0, 137.0, 119.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 162.0, 163.0, 161.0, 160.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 114.0, 76.0, 77.0, 74.0, 77.0, 102.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 110.0, 83.0, 83.0, 83.0, 93.0, 82.0, 83.0, 112.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 149.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 50.0, 49.0, 49.0, 50.0, 49.0, 48.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 65.0, 64.0, 64.0, 64.0, 67.0, 65.0, 65.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 80.0, 79.0, 84.0, 81.0, 77.0, 76.0, 76.0, 77.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 122.0, 121.0, 123.0, 123.0, 119.0, 119.0, 169.0, 135.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 157.0, 158.0, 160.0, 158.0, 163.0, 155.0, 171.0, 158.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 61.0, 61.0, 62.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 60.7, "unit": "score"}], "name": "kraken", "value": 1474.1, "alertThreshold": 2.0}]} 08:22:38 INFO - Return code: 0 08:22:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:22:38 ERROR - # TBPL SUCCESS # 08:22:38 INFO - Running post-action listener: _resource_record_post_action 08:22:38 INFO - [mozharness: 2017-02-03 16:22:38.473370Z] Finished run-tests step (success) 08:22:38 INFO - Running post-run listener: _resource_record_post_run 08:22:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 363438080; Read time: 0; Write time: 272780 08:22:38 INFO - TinderboxPrint: CPU usage
13.0% 08:22:38 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:22:38 INFO - TinderboxPrint: I/O write bytes / time
363,438,080 / 272,780 08:22:38 INFO - TinderboxPrint: CPU idle
8,262.2 (87.0%) 08:22:38 INFO - TinderboxPrint: CPU user
1,189.4 (12.5%) 08:22:38 INFO - TinderboxPrint: Swap in / out
0 / 0 08:22:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109428736; Read time: 0; Write time: 98328 08:22:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 254009344; Read time: 0; Write time: 174452 08:22:39 INFO - Running post-run listener: _upload_blobber_files 08:22:39 INFO - Blob upload gear active. 08:22:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:22:39 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 08:22:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:22:39 INFO - (blobuploader) - INFO - Open directory for files ... 08:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:22:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:40 INFO - (blobuploader) - INFO - Done attempting. 08:22:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:22:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:22:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:43 INFO - (blobuploader) - INFO - Done attempting. 08:22:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:22:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:44 INFO - (blobuploader) - INFO - Done attempting. 08:22:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:22:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:45 INFO - (blobuploader) - INFO - Done attempting. 08:22:45 INFO - (blobuploader) - INFO - Iteration through files over. 08:22:45 INFO - Return code: 0 08:22:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:22:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:22:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21f66b79cee0549723b736e7f8f8cd158e8d7300cf18e06aa7654d9132f5c5724c205174fbb77d8e336545adba3529a6abbe6548515a470f51d5619c62f5ba4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46ca11b22763a54132e2f6b19fb348badd397855955f56fd24b16600d7c599b82f9ecbbdc5fcdc11fa62657d907a1e884b2dffab695779c8e5337f12a2623383", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d751064cc7bd0c5df643c3e7793fe3b042d13680d5f181d9d2202c45871b8b630ea8069c3d4318db193a0d8de0e95b2d47875a341c784c070ce700dd8e89ab9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473acda6d4cc5c0d40f2c2d22a51805cc3cd9006c46c6c22e2ff2b16dd70ead992e32de4fcbe69a641f8137d37861bfb23a4448a7fdb1a10e83e5985675304e3"} 08:22:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:22:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:22:45 INFO - Contents: 08:22:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21f66b79cee0549723b736e7f8f8cd158e8d7300cf18e06aa7654d9132f5c5724c205174fbb77d8e336545adba3529a6abbe6548515a470f51d5619c62f5ba4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46ca11b22763a54132e2f6b19fb348badd397855955f56fd24b16600d7c599b82f9ecbbdc5fcdc11fa62657d907a1e884b2dffab695779c8e5337f12a2623383", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d751064cc7bd0c5df643c3e7793fe3b042d13680d5f181d9d2202c45871b8b630ea8069c3d4318db193a0d8de0e95b2d47875a341c784c070ce700dd8e89ab9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473acda6d4cc5c0d40f2c2d22a51805cc3cd9006c46c6c22e2ff2b16dd70ead992e32de4fcbe69a641f8137d37861bfb23a4448a7fdb1a10e83e5985675304e3"} 08:22:45 INFO - Running post-run listener: copy_logs_to_upload_dir 08:22:45 INFO - Copying logs to upload dir... 08:22:45 INFO - mkdir: /builds/slave/test/build/upload/logs 08:22:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.651954 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-03 08:22:44.996902) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 08:22:45.000402) ========= 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=5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21f66b79cee0549723b736e7f8f8cd158e8d7300cf18e06aa7654d9132f5c5724c205174fbb77d8e336545adba3529a6abbe6548515a470f51d5619c62f5ba4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46ca11b22763a54132e2f6b19fb348badd397855955f56fd24b16600d7c599b82f9ecbbdc5fcdc11fa62657d907a1e884b2dffab695779c8e5337f12a2623383", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d751064cc7bd0c5df643c3e7793fe3b042d13680d5f181d9d2202c45871b8b630ea8069c3d4318db193a0d8de0e95b2d47875a341c784c070ce700dd8e89ab9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473acda6d4cc5c0d40f2c2d22a51805cc3cd9006c46c6c22e2ff2b16dd70ead992e32de4fcbe69a641f8137d37861bfb23a4448a7fdb1a10e83e5985675304e3"} build_url:https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011359 build_url: 'https://queue.taskcluster.net/v1/task/M6xI86qjR1-WS9XCjj5_tQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21f66b79cee0549723b736e7f8f8cd158e8d7300cf18e06aa7654d9132f5c5724c205174fbb77d8e336545adba3529a6abbe6548515a470f51d5619c62f5ba4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46ca11b22763a54132e2f6b19fb348badd397855955f56fd24b16600d7c599b82f9ecbbdc5fcdc11fa62657d907a1e884b2dffab695779c8e5337f12a2623383", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d751064cc7bd0c5df643c3e7793fe3b042d13680d5f181d9d2202c45871b8b630ea8069c3d4318db193a0d8de0e95b2d47875a341c784c070ce700dd8e89ab9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473acda6d4cc5c0d40f2c2d22a51805cc3cd9006c46c6c22e2ff2b16dd70ead992e32de4fcbe69a641f8137d37861bfb23a4448a7fdb1a10e83e5985675304e3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 08:22:45.024840) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:22:45.025157) ========= 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=5bd038846534b2fdfa68eeae000001e2-1486137727.576020-116092295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:22:45.108793) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 08:22:45.109145) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 08:22:45.109516) ========= ========= Total master_lag: 0.15 =========