builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1490662510.1 results: success (0) revision: a8bb5c8e0e80e87e21ad63997f8881991b5347cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.099339) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.100043) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.100558) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.113685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.113974) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-27 17:55:10-- 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% 113M=0s 2017-03-27 17:55:10 (113 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122380 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.247952) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.248276) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018333 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:10.299427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 17:55:10.299864) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8bb5c8e0e80e87e21ad63997f8881991b5347cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev a8bb5c8e0e80e87e21ad63997f8881991b5347cf --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=05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-27 17:55:10,882 truncating revision to first 12 chars 2017-03-27 17:55:10,882 Setting DEBUG logging. 2017-03-27 17:55:10,882 attempt 1/10 2017-03-27 17:55:10,882 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8bb5c8e0e80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 17:55:12,261 unpacking tar archive at: mozilla-central-a8bb5c8e0e80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.745090 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 17:55:12.061234) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:12.061777) ========= script_repo_revision: a8bb5c8e0e80e87e21ad63997f8881991b5347cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:12.062395) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:12.062828) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 17:55:12.075148) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-27 17:55:12.075550) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:55:12 INFO - MultiFileLogger online at 20170327 17:55:12 in /builds/slave/test 17:55:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:55:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:55:12 INFO - {'append_to_log': False, 17:55:12 INFO - 'base_work_dir': '/builds/slave/test', 17:55:12 INFO - 'blob_upload_branch': 'Firefox', 17:55:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:55:12 INFO - 'branch': 'Firefox', 17:55:12 INFO - 'buildbot_json_path': 'buildprops.json', 17:55:12 INFO - 'config_files': ('talos/linux_config.py',), 17:55:12 INFO - 'default_actions': ('clobber', 17:55:12 INFO - 'read-buildbot-config', 17:55:12 INFO - 'download-and-extract', 17:55:12 INFO - 'populate-webroot', 17:55:12 INFO - 'create-virtualenv', 17:55:12 INFO - 'install', 17:55:12 INFO - 'run-tests'), 17:55:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:55:12 INFO - 'download_minidump_stackwalk': True, 17:55:12 INFO - 'download_symbols': 'ondemand', 17:55:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:55:12 INFO - 'tooltool.py': '/tools/tooltool.py', 17:55:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:55:12 INFO - '/tools/misc-python/virtualenv.py')}, 17:55:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:55:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:55:12 INFO - 'gecko_profile': False, 17:55:12 INFO - 'gecko_profile_interval': 0, 17:55:12 INFO - 'installer_path': 'installer.exe', 17:55:12 INFO - 'log_level': 'info', 17:55:12 INFO - 'log_name': 'talos', 17:55:12 INFO - 'log_to_console': True, 17:55:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:55:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:55:12 INFO - 'opt_config_files': (), 17:55:12 INFO - 'pip_index': False, 17:55:12 INFO - 'suite': 'dromaeojs', 17:55:12 INFO - 'system_bits': '32', 17:55:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:55:12 INFO - 'title': 'talos-linux64-ix-069', 17:55:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:55:12 INFO - 'use_talos_json': True, 17:55:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:55:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:55:12 INFO - 'work_dir': 'build'} 17:55:12 INFO - [mozharness: 2017-03-28 00:55:12.757068Z] Running clobber step. 17:55:12 INFO - Running pre-action listener: _resource_record_pre_action 17:55:12 INFO - Running main action method: clobber 17:55:12 INFO - rmtree: /builds/slave/test/build 17:55:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:55:13 INFO - Running post-action listener: _resource_record_post_action 17:55:13 INFO - [mozharness: 2017-03-28 00:55:13.708035Z] Finished clobber step (success) 17:55:13 INFO - [mozharness: 2017-03-28 00:55:13.708136Z] Running read-buildbot-config step. 17:55:13 INFO - Running pre-action listener: _resource_record_pre_action 17:55:13 INFO - Running main action method: read_buildbot_config 17:55:13 INFO - Using buildbot properties: 17:55:13 INFO - { 17:55:13 INFO - "project": "", 17:55:13 INFO - "product": "firefox", 17:55:13 INFO - "who": "kwierso@gmail.com", 17:55:13 INFO - "installer_path": "public/build/target.tar.bz2", 17:55:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 17:55:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:55:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 17:55:13 INFO - "stage_platform": "linux64-pgo", 17:55:13 INFO - "basedir": "/builds/slave/test", 17:55:13 INFO - "buildnumber": 98, 17:55:13 INFO - "platform": "ubuntu64_hw", 17:55:13 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:55:13 INFO - "slavebuilddir": "test", 17:55:13 INFO - "taskId": "VPGAMemMQJ6M06B3G0_5IA", 17:55:13 INFO - "branch": "mozilla-central", 17:55:13 INFO - "script_repo_revision": "production", 17:55:13 INFO - "revision": "a8bb5c8e0e80e87e21ad63997f8881991b5347cf", 17:55:13 INFO - "slavename": "talos-linux64-ix-069", 17:55:13 INFO - "repo_path": "mozilla-central" 17:55:13 INFO - } 17:55:13 INFO - Finding installer, test and symbols from parent task. 17:55:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VPGAMemMQJ6M06B3G0_5IA'}, attempt #1 17:55:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VPGAMemMQJ6M06B3G0_5IA'}, attempt #1 17:55:14 INFO - Task dependencies: Zi5T8S9yTPmZRgtHSYAJHA 17:55:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA'}, attempt #1 17:55:15 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2 17:55:15 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/test_packages.json 17:55:15 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.crashreporter-symbols.zip 17:55:15 INFO - Running post-action listener: _resource_record_post_action 17:55:15 INFO - [mozharness: 2017-03-28 00:55:15.098223Z] Finished read-buildbot-config step (success) 17:55:15 INFO - [mozharness: 2017-03-28 00:55:15.098441Z] Running download-and-extract step. 17:55:15 INFO - Running pre-action listener: _resource_record_pre_action 17:55:15 INFO - Running main action method: download_and_extract 17:55:15 INFO - mkdir: /builds/slave/test/build/tests 17:55:15 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')]} 17:55:15 INFO - trying https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.test_packages.json 17:55:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:55:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:55:15 INFO - Downloaded 986 bytes. 17:55:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:55:15 INFO - Using the following test package requirements: 17:55:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:55:15 INFO - u'common': [u'target.common.tests.zip'], 17:55:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:55:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:55:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:55:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:55:15 INFO - u'mozbase': [u'target.common.tests.zip'], 17:55:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:55:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:55:15 INFO - u'web-platform': [u'target.common.tests.zip', 17:55:15 INFO - u'target.web-platform.tests.zip'], 17:55:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:55:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:55:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.common.tests.zip 17:55:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:55:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.common.tests.zip into memory 17:55:16 INFO - Expected file size: 60259678 17:55:16 INFO - Obtained file size: 60259678 17:55:26 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.common.tests.zip 17:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.common.tests.zip into memory 17:55:28 INFO - Expected file size: 60259678 17:55:28 INFO - Obtained file size: 60259678 17:55:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.talos.tests.zip 17:55:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:55:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.talos.tests.zip into memory 17:55:39 INFO - Expected file size: 14404171 17:55:39 INFO - Obtained file size: 14404171 17:55: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')]} 17:55:39 INFO - trying https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2 17:55:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:55:41 INFO - Downloaded 68036043 bytes. 17:55:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2 17:55:41 INFO - mkdir: /builds/slave/test/properties 17:55:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:55:41 INFO - Writing to file /builds/slave/test/properties/build_url 17:55:41 INFO - Contents: 17:55:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2 17:55:41 INFO - Running post-action listener: _resource_record_post_action 17:55:41 INFO - Running post-action listener: set_extra_try_arguments 17:55:41 INFO - [mozharness: 2017-03-28 00:55:41.081947Z] Finished download-and-extract step (success) 17:55:41 INFO - [mozharness: 2017-03-28 00:55:41.082045Z] Running populate-webroot step. 17:55:41 INFO - Running pre-action listener: _resource_record_pre_action 17:55:41 INFO - Running main action method: populate_webroot 17:55:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:55:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:55:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:55:41 INFO - '--tppagecycles', 17:55:41 INFO - '7'], 17:55:41 INFO - 'tests': ['tsvgm']}}, 17:55:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:55:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:55:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:55:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:55:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:55:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:55:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['damp', 'tps']}, 17:55:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'tests': ['damp', 'tps']}, 17:55:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['dromaeo_dom']}, 17:55:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:55:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['basic_compositor_video']}, 17:55:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:55:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['a11yr', 17:55:41 INFO - 'ts_paint', 17:55:41 INFO - 'tpaint', 17:55:41 INFO - 'sessionrestore', 17:55:41 INFO - 'sessionrestore_no_auto_restore', 17:55:41 INFO - 'tabpaint']}, 17:55:41 INFO - 'other-e10s': {'tests': ['a11yr', 17:55:41 INFO - 'ts_paint', 17:55:41 INFO - 'tpaint', 17:55:41 INFO - 'sessionrestore', 17:55:41 INFO - 'sessionrestore_no_auto_restore', 17:55:41 INFO - 'tabpaint']}, 17:55:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:55:41 INFO - 'ts_paint', 17:55:41 INFO - 'tpaint', 17:55:41 INFO - 'sessionrestore', 17:55:41 INFO - 'sessionrestore_no_auto_restore', 17:55:41 INFO - 'tabpaint']}, 17:55:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:55:41 INFO - 'ts_paint', 17:55:41 INFO - 'tpaint', 17:55:41 INFO - 'sessionrestore', 17:55:41 INFO - 'sessionrestore_no_auto_restore', 17:55:41 INFO - 'tabpaint']}, 17:55:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['a11yr', 17:55:41 INFO - 'ts_paint', 17:55:41 INFO - 'tpaint', 17:55:41 INFO - 'sessionrestore', 17:55:41 INFO - 'sessionrestore_no_auto_restore', 17:55:41 INFO - 'tabpaint']}, 17:55:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['a11yr', 17:55:41 INFO - 'ts_paint', 17:55:41 INFO - 'tpaint', 17:55:41 INFO - 'sessionrestore', 17:55:41 INFO - 'sessionrestore_no_auto_restore', 17:55:41 INFO - 'tabpaint']}, 17:55:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['tsvgx', 17:55:41 INFO - 'tsvgr_opacity', 17:55:41 INFO - 'tart', 17:55:41 INFO - 'tscrollx', 17:55:41 INFO - 'cart', 17:55:41 INFO - 'tsvg_static']}, 17:55:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:55:41 INFO - 'tsvgr_opacity', 17:55:41 INFO - 'tart', 17:55:41 INFO - 'tscrollx', 17:55:41 INFO - 'cart', 17:55:41 INFO - 'tsvg_static']}, 17:55:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'talos_options': ['--disable-e10s'], 17:55:41 INFO - 'tests': ['tp5o']}, 17:55:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:55:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'talos_options': ['--disable-e10s', 17:55:41 INFO - '--xperf_path', 17:55:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:55:41 INFO - 'tests': ['tp5n']}, 17:55:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:55:41 INFO - 'talos_options': ['--xperf_path', 17:55:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:55:41 INFO - 'tests': ['tp5n']}}, 17:55:41 INFO - 'talos.zip': {'path': '', 17:55:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:55:41 INFO - Running post-action listener: _resource_record_post_action 17:55:41 INFO - [mozharness: 2017-03-28 00:55:41.095322Z] Finished populate-webroot step (success) 17:55:41 INFO - [mozharness: 2017-03-28 00:55:41.095413Z] Running create-virtualenv step. 17:55:41 INFO - Running pre-action listener: _resource_record_pre_action 17:55:41 INFO - Running main action method: create_virtualenv 17:55:41 INFO - Creating virtualenv /builds/slave/test/build/venv 17:55:41 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 17:55:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:55:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:55:41 INFO - Using real prefix '/usr' 17:55:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:55:41 INFO - Installing distribute.............................................................................................................................................................................................done. 17:55:43 INFO - Installing pip.................done. 17:55:43 INFO - Return code: 0 17:55:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:55:43 INFO - Reading from file tmpfile_stdout 17:55:43 INFO - Output received: 17:55:43 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) 17:55:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:55: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')]} 17:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55: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')]} 17:55:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:55:43 INFO - Using env: {'DISPLAY': ':0', 17:55:43 INFO - 'HOME': '/home/cltbld', 17:55:43 INFO - 'LANG': 'en_US.UTF-8', 17:55:43 INFO - 'LANGUAGE': 'en_US:en', 17:55:43 INFO - 'LOGNAME': 'cltbld', 17:55:43 INFO - 'MAIL': '/var/mail/cltbld', 17:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:43 INFO - 'MOZ_NO_REMOTE': '1', 17:55:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:43 INFO - 'NO_EM_RESTART': '1', 17:55:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:43 INFO - 'PWD': '/builds/slave/test', 17:55:43 INFO - 'SHELL': '/bin/bash', 17:55:43 INFO - 'SHLVL': '1', 17:55:43 INFO - 'TERM': 'linux', 17:55:43 INFO - 'TMOUT': '86400', 17:55:43 INFO - 'USER': 'cltbld', 17:55:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:43 INFO - '_': '/tools/buildbot/bin/python'} 17:55:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:44 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 17:55:44 INFO - Cleaning up... 17:55:44 INFO - Return code: 0 17:55:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:55:44 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')]} 17:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:44 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')]} 17:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:44 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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:44 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 17:55:44 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 17:55:44 INFO - Using env: {'DISPLAY': ':0', 17:55:44 INFO - 'HOME': '/home/cltbld', 17:55:44 INFO - 'LANG': 'en_US.UTF-8', 17:55:44 INFO - 'LANGUAGE': 'en_US:en', 17:55:44 INFO - 'LOGNAME': 'cltbld', 17:55:44 INFO - 'MAIL': '/var/mail/cltbld', 17:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:44 INFO - 'MOZ_NO_REMOTE': '1', 17:55:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:44 INFO - 'NO_EM_RESTART': '1', 17:55:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:44 INFO - 'PWD': '/builds/slave/test', 17:55:44 INFO - 'SHELL': '/bin/bash', 17:55:44 INFO - 'SHLVL': '1', 17:55:44 INFO - 'TERM': 'linux', 17:55:44 INFO - 'TMOUT': '86400', 17:55:44 INFO - 'USER': 'cltbld', 17:55:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:44 INFO - '_': '/tools/buildbot/bin/python'} 17:55:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:44 INFO - Downloading/unpacking psutil>=3.1.1 17:55: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 17:55: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 17:55: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 17:55: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 17:55: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 17:55: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 17:55:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:55:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:55:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:55:46 INFO - Installing collected packages: psutil 17:55:46 INFO - Running setup.py install for psutil 17:55:46 INFO - building 'psutil._psutil_linux' extension 17:55:46 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 17:55:46 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 17:55:46 INFO - building 'psutil._psutil_posix' extension 17:55:46 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 17:55:46 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 17:55:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:55:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:55:46 INFO - Successfully installed psutil 17:55:46 INFO - Cleaning up... 17:55:47 INFO - Return code: 0 17:55:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:55: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')]} 17:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55: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')]} 17:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:55: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 mozsystemmonitor==0.3 17:55:47 INFO - Using env: {'DISPLAY': ':0', 17:55:47 INFO - 'HOME': '/home/cltbld', 17:55:47 INFO - 'LANG': 'en_US.UTF-8', 17:55:47 INFO - 'LANGUAGE': 'en_US:en', 17:55:47 INFO - 'LOGNAME': 'cltbld', 17:55:47 INFO - 'MAIL': '/var/mail/cltbld', 17:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:47 INFO - 'MOZ_NO_REMOTE': '1', 17:55:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:47 INFO - 'NO_EM_RESTART': '1', 17:55:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:47 INFO - 'PWD': '/builds/slave/test', 17:55:47 INFO - 'SHELL': '/bin/bash', 17:55:47 INFO - 'SHLVL': '1', 17:55:47 INFO - 'TERM': 'linux', 17:55:47 INFO - 'TMOUT': '86400', 17:55:47 INFO - 'USER': 'cltbld', 17:55:47 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:47 INFO - '_': '/tools/buildbot/bin/python'} 17:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:55:47 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 17:55:47 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 17:55:47 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 17:55:47 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 17:55:47 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 17:55:47 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 17:55:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:55:49 INFO - Installing collected packages: mozsystemmonitor 17:55:49 INFO - Running setup.py install for mozsystemmonitor 17:55:49 INFO - Successfully installed mozsystemmonitor 17:55:49 INFO - Cleaning up... 17:55:49 INFO - Return code: 0 17:55:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:49 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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:49 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 17:55:49 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 17:55:49 INFO - Using env: {'DISPLAY': ':0', 17:55:49 INFO - 'HOME': '/home/cltbld', 17:55:49 INFO - 'LANG': 'en_US.UTF-8', 17:55:49 INFO - 'LANGUAGE': 'en_US:en', 17:55:49 INFO - 'LOGNAME': 'cltbld', 17:55:49 INFO - 'MAIL': '/var/mail/cltbld', 17:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:49 INFO - 'MOZ_NO_REMOTE': '1', 17:55:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:49 INFO - 'NO_EM_RESTART': '1', 17:55:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:49 INFO - 'PWD': '/builds/slave/test', 17:55:49 INFO - 'SHELL': '/bin/bash', 17:55:49 INFO - 'SHLVL': '1', 17:55:49 INFO - 'TERM': 'linux', 17:55:49 INFO - 'TMOUT': '86400', 17:55:49 INFO - 'USER': 'cltbld', 17:55:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:49 INFO - '_': '/tools/buildbot/bin/python'} 17:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:49 INFO - Downloading/unpacking jsonschema==2.5.1 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:55:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:51 INFO - Downloading functools32-3.2.3-2.tar.gz 17:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:55:51 INFO - warning: no files found matching '*.txt' 17:55:51 INFO - no previously-included directories found matching 'build' 17:55:51 INFO - no previously-included directories found matching 'dist' 17:55:51 INFO - no previously-included directories found matching '.git*' 17:55:51 INFO - Installing collected packages: jsonschema, functools32 17:55:52 INFO - Running setup.py install for functools32 17:55:52 INFO - warning: no files found matching '*.txt' 17:55:52 INFO - no previously-included directories found matching 'build' 17:55:52 INFO - no previously-included directories found matching 'dist' 17:55:52 INFO - no previously-included directories found matching '.git*' 17:55:52 INFO - Successfully installed jsonschema functools32 17:55:52 INFO - Cleaning up... 17:55:52 INFO - Return code: 0 17:55:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:55:52 INFO - Using env: {'DISPLAY': ':0', 17:55:52 INFO - 'HOME': '/home/cltbld', 17:55:52 INFO - 'LANG': 'en_US.UTF-8', 17:55:52 INFO - 'LANGUAGE': 'en_US:en', 17:55:52 INFO - 'LOGNAME': 'cltbld', 17:55:52 INFO - 'MAIL': '/var/mail/cltbld', 17:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:52 INFO - 'MOZ_NO_REMOTE': '1', 17:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:52 INFO - 'NO_EM_RESTART': '1', 17:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:52 INFO - 'PWD': '/builds/slave/test', 17:55:52 INFO - 'SHELL': '/bin/bash', 17:55:52 INFO - 'SHLVL': '1', 17:55:52 INFO - 'TERM': 'linux', 17:55:52 INFO - 'TMOUT': '86400', 17:55:52 INFO - 'USER': 'cltbld', 17:55:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:52 INFO - '_': '/tools/buildbot/bin/python'} 17:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:55:52 INFO - Cleaning up... 17:55:52 INFO - Return code: 0 17:55:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:55:52 INFO - Using env: {'DISPLAY': ':0', 17:55:52 INFO - 'HOME': '/home/cltbld', 17:55:52 INFO - 'LANG': 'en_US.UTF-8', 17:55:52 INFO - 'LANGUAGE': 'en_US:en', 17:55:52 INFO - 'LOGNAME': 'cltbld', 17:55:52 INFO - 'MAIL': '/var/mail/cltbld', 17:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:52 INFO - 'MOZ_NO_REMOTE': '1', 17:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:52 INFO - 'NO_EM_RESTART': '1', 17:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:52 INFO - 'PWD': '/builds/slave/test', 17:55:52 INFO - 'SHELL': '/bin/bash', 17:55:52 INFO - 'SHLVL': '1', 17:55:52 INFO - 'TERM': 'linux', 17:55:52 INFO - 'TMOUT': '86400', 17:55:52 INFO - 'USER': 'cltbld', 17:55:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:52 INFO - '_': '/tools/buildbot/bin/python'} 17:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:52 INFO - Downloading/unpacking blobuploader==1.2.4 17:55:52 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 17:55:52 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 17:55:52 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 17:55:52 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 17:55:52 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 17:55:52 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 17:55:54 INFO - Downloading blobuploader-1.2.4.tar.gz 17:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:55:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:55:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:55:55 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 17:55:55 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 17:55:55 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 17:55:55 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 17:55:55 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 17:55:55 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 17:55:55 INFO - Downloading docopt-0.6.1.tar.gz 17:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:55:55 INFO - Installing collected packages: blobuploader, requests, docopt 17:55:55 INFO - Running setup.py install for blobuploader 17:55:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:55:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:55:55 INFO - Running setup.py install for requests 17:55:55 INFO - Running setup.py install for docopt 17:55:55 INFO - Successfully installed blobuploader requests docopt 17:55:55 INFO - Cleaning up... 17:55:56 INFO - Return code: 0 17:55:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:56 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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:56 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 17:55:56 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 17:55:56 INFO - Using env: {'DISPLAY': ':0', 17:55:56 INFO - 'HOME': '/home/cltbld', 17:55:56 INFO - 'LANG': 'en_US.UTF-8', 17:55:56 INFO - 'LANGUAGE': 'en_US:en', 17:55:56 INFO - 'LOGNAME': 'cltbld', 17:55:56 INFO - 'MAIL': '/var/mail/cltbld', 17:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:56 INFO - 'MOZ_NO_REMOTE': '1', 17:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:56 INFO - 'NO_EM_RESTART': '1', 17:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:56 INFO - 'PWD': '/builds/slave/test', 17:55:56 INFO - 'SHELL': '/bin/bash', 17:55:56 INFO - 'SHLVL': '1', 17:55:56 INFO - 'TERM': 'linux', 17:55:56 INFO - 'TMOUT': '86400', 17:55:56 INFO - 'USER': 'cltbld', 17:55:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:56 INFO - '_': '/tools/buildbot/bin/python'} 17:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:55:56 INFO - Running setup.py (path:/tmp/pip-Q3xNtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:55:56 INFO - Running setup.py (path:/tmp/pip-2cZX8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:55:56 INFO - Running setup.py (path:/tmp/pip-agnmxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:55:56 INFO - Running setup.py (path:/tmp/pip-cW8KOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:55:56 INFO - Running setup.py (path:/tmp/pip-G1C5SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:55:56 INFO - Running setup.py (path:/tmp/pip-jUUbxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:55:56 INFO - Running setup.py (path:/tmp/pip-L9SYG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:55:56 INFO - Running setup.py (path:/tmp/pip-WalSZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:55:56 INFO - Running setup.py (path:/tmp/pip-9Qi1ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:55:56 INFO - Running setup.py (path:/tmp/pip-gg4pX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:55:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:55:56 INFO - Running setup.py (path:/tmp/pip-W5HeNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:55:57 INFO - Running setup.py (path:/tmp/pip-NFDnhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:55:57 INFO - Running setup.py (path:/tmp/pip-rPZMSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:55:57 INFO - Running setup.py (path:/tmp/pip-1qfayT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:55:57 INFO - Running setup.py (path:/tmp/pip-snkwAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:55:57 INFO - Running setup.py (path:/tmp/pip-nFbqPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:55:57 INFO - Running setup.py (path:/tmp/pip-l2Tmji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:55:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:55:57 INFO - Running setup.py install for manifestparser 17:55:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:55:57 INFO - Running setup.py install for mozcrash 17:55:57 INFO - Running setup.py install for mozdebug 17:55:57 INFO - Running setup.py install for mozdevice 17:55:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:55:57 INFO - Running setup.py install for mozfile 17:55:58 INFO - Running setup.py install for mozhttpd 17:55:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for mozinfo 17:55:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for mozInstall 17:55:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for mozleak 17:55:58 INFO - Running setup.py install for mozlog 17:55:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for moznetwork 17:55:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for mozprocess 17:55:58 INFO - Running setup.py install for mozprofile 17:55:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for mozrunner 17:55:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:55:58 INFO - Running setup.py install for mozscreenshot 17:55:59 INFO - Running setup.py install for moztest 17:55:59 INFO - Running setup.py install for mozversion 17:55:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:55:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:55:59 INFO - Cleaning up... 17:55:59 INFO - Return code: 0 17:55:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:59 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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:55:59 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 17:55:59 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 17:55:59 INFO - Using env: {'DISPLAY': ':0', 17:55:59 INFO - 'HOME': '/home/cltbld', 17:55:59 INFO - 'LANG': 'en_US.UTF-8', 17:55:59 INFO - 'LANGUAGE': 'en_US:en', 17:55:59 INFO - 'LOGNAME': 'cltbld', 17:55:59 INFO - 'MAIL': '/var/mail/cltbld', 17:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:59 INFO - 'MOZ_NO_REMOTE': '1', 17:55:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:55:59 INFO - 'NO_EM_RESTART': '1', 17:55:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:59 INFO - 'PWD': '/builds/slave/test', 17:55:59 INFO - 'SHELL': '/bin/bash', 17:55:59 INFO - 'SHLVL': '1', 17:55:59 INFO - 'TERM': 'linux', 17:55:59 INFO - 'TMOUT': '86400', 17:55:59 INFO - 'USER': 'cltbld', 17:55:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:59 INFO - '_': '/tools/buildbot/bin/python'} 17:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:55:59 INFO - Running setup.py (path:/tmp/pip-QNHqfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:55:59 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)) 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:55:59 INFO - Running setup.py (path:/tmp/pip-JIs3Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:55:59 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)) 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:55:59 INFO - Running setup.py (path:/tmp/pip-75r0ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:55:59 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)) 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:55:59 INFO - Running setup.py (path:/tmp/pip-UL1cl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:55:59 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)) 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:55:59 INFO - Running setup.py (path:/tmp/pip-BCmToT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:55:59 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)) 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:55:59 INFO - Running setup.py (path:/tmp/pip-K1fJot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:55:59 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)) 17:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:55:59 INFO - Running setup.py (path:/tmp/pip-M_da2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:56:00 INFO - Running setup.py (path:/tmp/pip-loREJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:56:00 INFO - Running setup.py (path:/tmp/pip-wyRXYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:56:00 INFO - Running setup.py (path:/tmp/pip-T0uOUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:56:00 INFO - Running setup.py (path:/tmp/pip-Q1YeL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:56:00 INFO - Running setup.py (path:/tmp/pip-Jt2aWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:56:00 INFO - Running setup.py (path:/tmp/pip-wHF2Wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:56:00 INFO - Running setup.py (path:/tmp/pip-U6Ogfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:56:00 INFO - Running setup.py (path:/tmp/pip-keIYFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:56:00 INFO - Running setup.py (path:/tmp/pip-3Uyf7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:56:00 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)) 17:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:56:00 INFO - Running setup.py (path:/tmp/pip-MeFzXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:56:00 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)) 17:56:00 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)) 17:56:00 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)) 17:56:00 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)) 17:56:00 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)) 17:56:00 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)) 17:56:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:56:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:56:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:56:02 INFO - Downloading blessings-1.6.tar.gz 17:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:56:02 INFO - Installing collected packages: blessings 17:56:02 INFO - Running setup.py install for blessings 17:56:02 INFO - Successfully installed blessings 17:56:02 INFO - Cleaning up... 17:56:03 INFO - Return code: 0 17:56:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:56:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:56:03 INFO - Reading from file tmpfile_stdout 17:56:03 INFO - Current package versions: 17:56:03 INFO - argparse == 1.2.1 17:56:03 INFO - blessings == 1.6 17:56:03 INFO - blobuploader == 1.2.4 17:56:03 INFO - docopt == 0.6.1 17:56:03 INFO - functools32 == 3.2.3-2 17:56:03 INFO - jsonschema == 2.5.1 17:56:03 INFO - manifestparser == 1.1 17:56:03 INFO - mozInstall == 1.12 17:56:03 INFO - mozcrash == 1.0 17:56:03 INFO - mozdebug == 0.1 17:56:03 INFO - mozdevice == 0.49 17:56:03 INFO - mozfile == 1.2 17:56:03 INFO - mozhttpd == 0.7 17:56:03 INFO - mozinfo == 0.9 17:56:03 INFO - mozleak == 0.1 17:56:03 INFO - mozlog == 3.4 17:56:03 INFO - moznetwork == 0.27 17:56:03 INFO - mozprocess == 0.25 17:56:03 INFO - mozprofile == 0.28 17:56:03 INFO - mozrunner == 6.13 17:56:03 INFO - mozscreenshot == 0.1 17:56:03 INFO - mozsystemmonitor == 0.3 17:56:03 INFO - moztest == 0.8 17:56:03 INFO - mozversion == 1.4 17:56:03 INFO - psutil == 3.1.1 17:56:03 INFO - requests == 1.2.3 17:56:03 INFO - wsgiref == 0.1.2 17:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:56:03 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')]} 17:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:03 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')]} 17:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:03 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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:56:03 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 17:56:03 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 17:56:03 INFO - Using env: {'DISPLAY': ':0', 17:56:03 INFO - 'HOME': '/home/cltbld', 17:56:03 INFO - 'LANG': 'en_US.UTF-8', 17:56:03 INFO - 'LANGUAGE': 'en_US:en', 17:56:03 INFO - 'LOGNAME': 'cltbld', 17:56:03 INFO - 'MAIL': '/var/mail/cltbld', 17:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:03 INFO - 'MOZ_NO_REMOTE': '1', 17:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:56:03 INFO - 'NO_EM_RESTART': '1', 17:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:03 INFO - 'PWD': '/builds/slave/test', 17:56:03 INFO - 'SHELL': '/bin/bash', 17:56:03 INFO - 'SHLVL': '1', 17:56:03 INFO - 'TERM': 'linux', 17:56:03 INFO - 'TMOUT': '86400', 17:56:03 INFO - 'USER': 'cltbld', 17:56:03 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:03 INFO - '_': '/tools/buildbot/bin/python'} 17:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 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)) 17:56:03 INFO - Cleaning up... 17:56:03 INFO - Return code: 0 17:56:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:56:03 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')]} 17:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:03 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')]} 17:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:03 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 0x25de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x261ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x287c310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', '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 17:56:03 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 17:56:03 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 17:56:03 INFO - Using env: {'DISPLAY': ':0', 17:56:03 INFO - 'HOME': '/home/cltbld', 17:56:03 INFO - 'LANG': 'en_US.UTF-8', 17:56:03 INFO - 'LANGUAGE': 'en_US:en', 17:56:03 INFO - 'LOGNAME': 'cltbld', 17:56:03 INFO - 'MAIL': '/var/mail/cltbld', 17:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:03 INFO - 'MOZ_NO_REMOTE': '1', 17:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:56:03 INFO - 'NO_EM_RESTART': '1', 17:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:03 INFO - 'PWD': '/builds/slave/test', 17:56:03 INFO - 'SHELL': '/bin/bash', 17:56:03 INFO - 'SHLVL': '1', 17:56:03 INFO - 'TERM': 'linux', 17:56:03 INFO - 'TMOUT': '86400', 17:56:03 INFO - 'USER': 'cltbld', 17:56:03 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:03 INFO - '_': '/tools/buildbot/bin/python'} 17:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:56:03 INFO - Cleaning up... 17:56:03 INFO - Return code: 0 17:56:03 INFO - Running post-action listener: _resource_record_post_action 17:56:03 INFO - Running post-action listener: _start_resource_monitoring 17:56:03 INFO - Starting resource monitoring. 17:56:03 INFO - [mozharness: 2017-03-28 00:56:03.720577Z] Finished create-virtualenv step (success) 17:56:03 INFO - [mozharness: 2017-03-28 00:56:03.721153Z] Running install step. 17:56:03 INFO - Running pre-action listener: _resource_record_pre_action 17:56:03 INFO - Running main action method: install 17:56:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:56:03 INFO - Reading from file tmpfile_stdout 17:56:03 INFO - Detecting whether we're running mozinstall >=1.0... 17:56:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:56:04 INFO - Reading from file tmpfile_stdout 17:56:04 INFO - Output received: 17:56:04 INFO - Usage: mozinstall [options] installer 17:56:04 INFO - Options: 17:56:04 INFO - -h, --help show this help message and exit 17:56:04 INFO - -d DEST, --destination=DEST 17:56:04 INFO - Directory to install application into. [default: 17:56:04 INFO - "/builds/slave/test"] 17:56:04 INFO - --app=APP Application being installed. [default: firefox] 17:56:04 INFO - mkdir: /builds/slave/test/build/application 17:56:04 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'] 17:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:56:20 INFO - Reading from file tmpfile_stdout 17:56:20 INFO - Output received: 17:56:20 INFO - /builds/slave/test/build/application/firefox/firefox 17:56:20 INFO - Running post-action listener: _resource_record_post_action 17:56:20 INFO - [mozharness: 2017-03-28 00:56:20.928867Z] Finished install step (success) 17:56:20 INFO - [mozharness: 2017-03-28 00:56:20.929151Z] Running run-tests step. 17:56:20 INFO - Running pre-action listener: _resource_record_pre_action 17:56:20 INFO - Running main action method: run_tests 17:56:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:56:20 INFO - Python 2.7.3 17:56:20 INFO - Return code: 0 17:56:20 INFO - grabbing minidump binary from tooltool 17:56:20 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')]} 17:56:20 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 0x2879400>, '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 0x287a3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287a840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:56:20 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 17:56:20 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 17:56:20 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 17:56:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:56:21 INFO - Return code: 0 17:56:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:56:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:56:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:56:21 INFO - ENV: RUST_BACKTRACE is now 1 17:56:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:56:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:56:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 17:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 17:56:21 INFO - Using env: {'DISPLAY': ':0', 17:56:21 INFO - 'HOME': '/home/cltbld', 17:56:21 INFO - 'LANG': 'en_US.UTF-8', 17:56:21 INFO - 'LANGUAGE': 'en_US:en', 17:56:21 INFO - 'LOGNAME': 'cltbld', 17:56:21 INFO - 'MAIL': '/var/mail/cltbld', 17:56:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:56:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:21 INFO - 'MOZ_NO_REMOTE': '1', 17:56:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:56:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:56:21 INFO - 'NO_EM_RESTART': '1', 17:56:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:21 INFO - 'PWD': '/builds/slave/test', 17:56:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:56:21 INFO - 'RUST_BACKTRACE': '1', 17:56:21 INFO - 'SHELL': '/bin/bash', 17:56:21 INFO - 'SHLVL': '1', 17:56:21 INFO - 'TERM': 'linux', 17:56:21 INFO - 'TMOUT': '86400', 17:56:21 INFO - 'USER': 'cltbld', 17:56:21 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103', 17:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:21 INFO - '_': '/tools/buildbot/bin/python'} 17:56:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 17:56:21 INFO - mozversion application_buildid: 20170328000339 17:56:21 INFO - mozversion application_changeset: a8bb5c8e0e80e87e21ad63997f8881991b5347cf 17:56:21 INFO - mozversion application_display_name: Nightly 17:56:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:56:21 INFO - mozversion application_name: Firefox 17:56:21 INFO - mozversion application_remotingname: firefox 17:56:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:56:21 INFO - mozversion application_vendor: Mozilla 17:56:21 INFO - mozversion application_version: 55.0a1 17:56:21 INFO - mozversion platform_buildid: 20170328000339 17:56:21 INFO - mozversion platform_changeset: a8bb5c8e0e80e87e21ad63997f8881991b5347cf 17:56:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:56:21 INFO - mozversion platform_version: 55.0a1 17:56:21 INFO - using testdate: 1490662581 17:56:21 INFO - actual date: 1490662581 17:56:21 INFO - starting webserver on 'localhost:40219' 17:56:21 INFO - SUITE-START | Running 2 tests 17:56:21 INFO - TEST-START | dromaeo_css 17:56:21 INFO - operating with platform_type : linux_ 17:56:21 INFO - Initialising browser for dromaeo_css test... 17:56:21 INFO - TEST-INFO | started process 30203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo8ztWw/profile http://localhost:40219/getInfo.html) 17:56:24 INFO - PID 30203 | __metrics Screen width/height:1600/1200 17:56:24 INFO - PID 30203 | colorDepth:24 17:56:24 INFO - PID 30203 | Browser inner width/height: 1024/697 17:56:24 INFO - PID 30203 | __metrics 17:56:25 ERROR - PID 30203 | 1490662585792 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:56:25 INFO - PID 30203 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:56:25 INFO - PID 30203 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:56:26 INFO - TEST-INFO | 30203: exit 0 17:56:26 INFO - Browser initialized. 17:56:26 INFO - Running cycle 1/1 for dromaeo_css test... 17:56:26 INFO - TEST-INFO | started process 30298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo8ztWw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:56:38 INFO - PID 30298 | RSS: Main: 145440768 17:56:38 INFO - PID 30298 | 17:59:40 INFO - PID 30298 | Cycle 1(1): loaded http://localhost:40219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40219/tests/dromaeo/cssquery-ext.html) 17:59:41 INFO - PID 30298 | RSS: Main: 264007680 17:59:41 INFO - PID 30298 | 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | A coding exception was thrown and uncaught in a Task. 18:01:28 INFO - PID 30298 | 18:01:28 ERROR - PID 30298 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:01:28 INFO - PID 30298 | Full stack: 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | A coding exception was thrown and uncaught in a Task. 18:01:28 INFO - PID 30298 | 18:01:28 ERROR - PID 30298 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:01:28 INFO - PID 30298 | Full stack: 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | A coding exception was thrown and uncaught in a Task. 18:01:28 INFO - PID 30298 | 18:01:28 ERROR - PID 30298 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:01:28 INFO - PID 30298 | Full stack: 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | ************************* 18:01:28 INFO - PID 30298 | A coding exception was thrown and uncaught in a Task. 18:01:28 INFO - PID 30298 | 18:01:28 ERROR - PID 30298 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:01:28 INFO - PID 30298 | Full stack: 18:01:28 INFO - PID 30298 | ************************* 18:02:44 INFO - PID 30298 | Cycle 1(1): loaded http://localhost:40219/tests/dromaeo/cssquery-ext.html (next: http://localhost:40219/tests/dromaeo/cssquery-jquery.html) 18:02:44 INFO - PID 30298 | RSS: Main: 226000896 18:02:44 INFO - PID 30298 | 18:05:46 INFO - PID 30298 | Cycle 1(1): loaded http://localhost:40219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40219/tests/dromaeo/cssquery-mootools.html) 18:05:47 INFO - PID 30298 | RSS: Main: 210489344 18:05:47 INFO - PID 30298 | 18:08:49 INFO - PID 30298 | Cycle 1(1): loaded http://localhost:40219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40219/tests/dromaeo/cssquery-prototype.html) 18:08:49 INFO - PID 30298 | RSS: Main: 176607232 18:08:49 INFO - PID 30298 | 18:11:52 INFO - PID 30298 | Cycle 1(1): loaded http://localhost:40219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40219/tests/dromaeo/cssquery-yui.html) 18:11:53 INFO - PID 30298 | RSS: Main: 183418880 18:11:53 INFO - PID 30298 | 18:14:55 INFO - PID 30298 | Cycle 1(1): loaded http://localhost:40219/tests/dromaeo/cssquery-yui.html (next: http://localhost:40219/tests/dromaeo/cssquery-dojo.html) 18:14:56 INFO - PID 30298 | RSS: Main: 287137792 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | __start_tp_report 18:14:56 INFO - PID 30298 | _x_x_mozilla_page_load 18:14:56 INFO - PID 30298 | _x_x_mozilla_page_load_details 18:14:56 INFO - PID 30298 | |i|pagename|runs| 18:14:56 INFO - PID 30298 | |0;dojo.html;3308.4871844864037;3319.369319829234;3344.632090470049;3362.764606477546;3379.5944486661588;5709.71451427429;5782.45066718661;5834.066549352105;5844.766209351632;5866.266716660418;5744.856378590537;5808.680522571257;5819.418058194178;5827.766889324433;5838.095095260231;5163.896722065566;5210.973945130269;5235.607329450288;5248.632595718302;5287.41838397776;5279.5512381447625;5295.126304159809;5323.441038690939;5370.7046112463795;5375.596830237729;5288.762005709752;5306.416294207634;5309.707966061865;5314.309139811839;5318.25544423781;362.7330525098805;4743.193657078305;5173.715445650488;5183.041137389595;5301.7084060376665;5245.711485868275;5266.8683282917855;5274.709890955995;5290.709011004393;5307.973460132714;5802.88394232113;5814;5837.182794408786;5856.648601083949;5865.736041878125;6084.787032453887;6095.710389288371;6376.915924292882;6382.1384113144595;6383.32975037619;6185.41238582334;6187.81436556904;6209.534284928648;6209.720562574695;6256.812295631138;5864.472197502245;5869.8239052828485;5873.706314684291;5873.970630146908;5886.1465087562065;6348.841278968062;6411.96794016027;6443.742250310029;6445.355464453611;6455.838604034842;5933.9406605934255;6056;6065;6089.604175728564;6121.84695382619;6271.905921411182;6290.465310448663;6297.149884765498;6302.1492098566105;6305.495560355161;5952.5833191676165;6010.128531362928;6012.729427175787;6123.785667501706;6259.155014073133;6190.566660333733;6203.875922481525;6207.875842483125;6208.906866397007;6212.565120441525;4642.1644104060915;4681.531846815359;4710.552497512731;4720.763961801897;4780.617550595944;4194.2869712149;4208.116295577902;4222.9577704223175;4238.936415953764;4256.702030857872;5850.882982340415;5871.266091738533;5872.412758724094;5873.383294754075;5921.348651648315;6108.372425856702;6173.166622505904;6274.466670332981;6277.7175027123885;6316.399942005502;5869.559783016291;5895.793647222326;5932.851678707981;5933.228680271537;5944.375840536768;5313.867153321121;5324.7870085196155;5326.2543243945875;5329.733513324319;5332.173513105475;6187.195664563578;6539.673016349068;6545.05096760967;6548.607083575001;6551.181102362205;6204.472619827366;6281.434670879643;6294.968525157345;6326.082718005771;6329.240491141092;6507.12153859223;6521.902171467526;6536.150300460909;6544.411003009752;6549.934500654933;6124.846878827887;6155.507559395327;6160.13758073861;6162.414570615784;6166.044263139221;6104.641056839769;6226.782062627967;6231.813045608639;6259.655718935503;6268;6036.63780173191;6084.300305464928;6084.665343406155;6092.482139018233;6119.296280927572;5960.821175364746;6041.69791510414;6067.24159480065;6092.147099406176;6097.237845269341;6012.669303188367;6018.1875446815875;6020.939790602038;6057.818265452043;6087.269527656709;6210.7515699371515;6216.440520353009;6217.906731399123;6220.875582488416;6224.9377506224355;6040.728167232397;6064.120702498025;6064.514838812973;6066.514678825595;6118.724657390517 18:14:56 INFO - PID 30298 | |1;ext.html;607.3076692570468;664.6377724140343;679.5922446532081;1134.8467956825832;1135.1089394825053;29504.11487655371;36270.818645906766;36945.81527092361;37031.814840925756;37035.0741231469;39042.41436378456;39057.80471097641;39264.21471570567;39369.803150984204;39487.80256098723;6027.0658048002415;6116.357782432825;8224.547649879254;8407.663693452254;8413.116622754615;7876.173001834811;8035.598220088972;8040.276375126237;8050.758477245663;8052.959735201316;8369.455985360932;8446.282066024396;8470.195331443503;8471.872921906177;8483.194096560817;8376.455530390505;8419.663213471455;8450.873236901421;8456.323494120456;8457.365697572706;8240;8262.669493220264;8305.418620696553;8309.37679674021;8320.542370169638;40327.39508907369;40559.594404055875;40614.39078413826;40637;40639.18721625581;2987.268119310783;2993.236724635226;2999.1302522268516;3001.0996700989836;3036.104349216986;27603.723962760316;27812.582811257846;28192.43615127686;28373.290667733265;28960.855195723885;3657.6159503252306;3662.249238906018;3711.0907827582414;3715;3718.0518967663074;201708;202152;202972;203209;203340;50018;50192.247116293285;50404;50421.49578504241;50455.747721261156;53098.469015310126;53171;53172.46827531675;53221.733891330296;53250.73374633102;13309.267990260434;13554.661133471742;13595;13618.727625447436;13630.863691363158;9213.710080588722;9290.256779457628;10529.36823790575;10625.46872656364;10715;13246.536371226961;13335.133216340906;13399.196048237138;13512.729745405037;13533.120347177402;5371.973140134274;5375.247465354853;5393.9730301348245;5544.3346798384455;5577.470140336662;1028.99485502572;1030.108955753268;1031.242037102729;1034.798214348195;1045.8744950605976;25514.74485255161;25812.354691132496;25900.611490827654;26023.219303420927;26114.216573502825;1123.8370436286857;1124.8875112488686;1127.7518945831903;1138.7267055906523;1165.0892536240572;12173.634790956285;13171.407286672124;13354.198748075147;13504.392302346418;13504.93247533756;289436;289553;290383;291433;292052;55637.72181139149;55663.72168139133;55698;55812.44187558073;55877.7206113975;1423.366601862182;1462.7074585082812;1463.992680036593;1469.1478942213491;1472.0211059645217;23606.29181124569;24070.277891663278;25064.62403063992;25155;25252.368690782878;40196.799016004734;40245.79877100596;40405;40491.595084048786;40619;292093;292488;292596;293162;293298;32696.50955235626;33911;34010.31979360448;34121.82939085289;34257.82871085629;41798.373024405264;42043.7897810509;42173;42402.78798606111;43604.34593481037;32172;32179;32257;32667.509987350684;32924.34151317008;31493.370132597676;31565.842170788997;31768;31816.204594885312;32209.97375328084;36966.630333697394;37099.25801484009;37629.05927351838;37765.81117094397;37847.81076094602;27929;27990.860045699643;28183.71816281811;28310.71689283163;28488.715112848608;23556.764432356144;25598.872005639852;25684.871575642002;26016.34959126037;26048.8697556511 18:14:56 INFO - PID 30298 | |2;jquery.html;400.5093760143822;412.4885142423396;428.4601402233186;434.11007434758744;434.2270758050669;15187.620309492268;19401.320953766604;19892.50268743282;20037.09833057512;20076.8996155019;19684.11421486033;20554.89722551389;20705.482362940933;21934.341969740894;22128;5936.198613187229;5941.197938278342;6143.662098584598;6152.630842149485;6156.599821011642;5923.229980102581;6055.2430946131735;6055.727492262837;6059.515238780887;6059.848503787419;5794.5074668653215;5972.462478376945;6019.067044608071;6023.759049638009;6042.154098426224;5935.317438494563;5959.195508606348;5986.401359864022;5995;5998.25021872266;5385.272988146589;5410.1343784994215;5445.891082178374;5458.426865179139;5465.672059676433;20349.389518314474;20576.794232057637;20823.271185508587;25085.24744257675;25164.370890727696;1997.2310660395708;2002.9499262518407;2004.789497102798;2008.8593798434115;2020.1515363547346;15463.536093917088;16286.83713162865;16498.505044848673;16571.42000029993;16866.28941632839;1821.2623887325653;1826.4155470249525;1832.0931139086238;1851.139220262571;1872.5253788217158;94237;95036;95231;96236;98286.50856745814;10849;11875.40622968848;12393.318367489694;12493.625191244277;12721.745565088646;15894.205289735699;17385.652286954442;17865.285388584573;17884.463466096036;17887.821121788875;8500.957495212608;8581;8661.696840610548;8838.116188381111;8966.386050510971;7108.964455177691;7327.157376901618;7424.071991001125;7620.123685776096;7839.529628222325;7777.1833957433655;8029.237222463857;8054.59727013647;8067.677292908335;8189.829695130795;2266.46738016566;2306.434923443767;2338.731045929706;2366.9053237870294;2387.1644924276366;1004.9045340692622;1014.8071866345371;1014.9898501014895;1018.3431686562126;1038.7091614347994;19413.223471061283;19483.707744383846;19736.210551578064;19852.205911763656;19960.60078798445;1014.4826138669332;1031.9845202321972;1032.4476405123223;1036.3471013261596;1051.9053285204368;10206.693799186036;10864.511097000654;10889.673309800719;10895.128389728803;10929.945350273358;247339;251685;612647.0514711414;624180;651604;101429;101669.44156954471;103828;104792;106648;1172.935488548121;1207.3963018490754;1213.805791073424;1221.126894270583;1232.371490539831;15950.650363423347;16526.83473165253;16821.74767378466;17087.316507340063;17233.138343082544;46244.76877615591;46441.30338044865;46511.53488465072;46517.76741116273;47299.290510643055;59708.402915970284;59972.700136499036;60979.69510152421;61912;62706.37293627188;9170.816583668422;9230.400023998418;9422.811543768948;9568.186704130227;9737.07497787709;13767.518136865161;13950.720985580028;14320;14402.775764060172;14410.351534180778;10079.496025198858;10400.531976061247;11795.410229488663;12394.442250098948;12588.363512743284;11125.332480051224;11352.318860868374;11500.654980350602;11583.8262426062;11638.12714046433;12629.74740505203;12634.873651263737;12909.677258068623;13027.544035958696;13114.868851311747;9474.289428293043;9529.332946693943;9580.13778759915;9635.181009614262;9658.696076029646;11971;12035.277883327028;12057.336846473529;12322;12693.492260309484 18:14:56 INFO - PID 30298 | |3;mootools.html;1287.6716437308485;1316.8946484281253;1320.7160460500997;1332.640187149469;1338.0432990411853;10382.896171038288;10844.186685998562;10896.019358257756;10897.237193396466;10901.454927253635;9899.50502474877;10147.238957078212;10148.847767283478;10221.540030698618;10693.946530267358;2303.0442366417915;2332.2303639798824;2336.976630233693;2338.205010296499;2342.5666251743482;1987.761468623767;2162.697222388867;2200.4058904095887;2207.9889600551974;2271.114265436473;2039.7654269759018;2307.8038366738847;2381.047580967618;2383.5113801670705;2383.976160238393;2251.549690061978;2311.687922130516;2313.7801908818637;2344.5310937812505;2344.753800850912;2242.058335499078;2248.640217565198;2258.2096266306826;2261.8416710830247;2266.29744779119;10243.385396876136;10281.48592570376;10369.481525923673;10370.533326000274;10399.116075133623;5828.708564571754;6010.398960103955;6014.188084608566;6017.338092809788;6022.277326720778;3689.7786132831848;3719.2375563009787;3794.165283637595;3808;3815.4467602197524;1369.5754316162022;1860.6185731924922;1897.5256185953513;1916.2047750183658;1961.5880665060354;15834.524964251092;16126.596835079099;16134.273957671932;16138.515844524683;16165.434209802717;5103.540681338697;5180.248863914711;5184.455632158644;5197.532222100029;5226.973865130726;12099.939500302442;12102.636920892388;12117.87882121185;12137.150399471951;12159.088068394729;6163.753449861955;6163.815085547351;6167.044108163242;6175.5677102602385;6178.1350610913605;5597.104463285855;5597.216389705444;5632.155176723442;5703.458171473703;5707.286589176353;4052.5542190359038;4145;4161.500619925568;4164.500259968823;4176.102138040336;1067.3969207397795;1098.653924013933;1179.7876382251277;1186.9821952670716;1196.6888608961733;807.5518087461516;824.1922915542801;836.2445741282489;850.7405241401308;854.9145085491401;6149.84625384369;6167.568270221132;6264.906026409608;6271.153394291803;6371.324043901573;915.8946721127155;921.8432866412726;935.9812803743887;943.2218419803689;948.2082461145021;4009.1179940413062;4566.383538222298;4606.930896036562;4800.4719480857075;4915.778789954461;13758.243296618582;14045.367958441895;14126.32715493979;14147.151170929777;14169.78745318821;13700.3834827433;14092.084014539023;14098.224597647022;14246.145231286157;14282.07166534172;10194.847077293847;10354.326968747007;10365.585376585002;10428.791424171473;10473.895261047444;6226.750929962751;6456.774012909714;6499.772507962199;6598.571092878948;6849.315068493111;12046.638600841989;12675.239485630891;12824.23054616726;13480.79778803299;13524.391402387113;12718.68203294888;13023;13387.799183012068;13403.865961340653;13620.455181793013;9459.479728614991;9492.382995105296;9592.328537002342;9671.129598336183;9703.951480242553;9759.951200243953;10291.279610427198;10502.632407865687;10534.473276335999;10542.472876356305;8445.366597505088;8486.61810218554;8537.74386768398;8729.65081396737;8800.47197168172;8419.494830310201;8609.82780344402;8748.1251874812;8783.648654053766;8976.102389760972;7128.928710712827;7178.21168986256;7253.310935461122;7253.528520646141;7255.129384473897;5926.733297001731;5950.7917222896995;5989.64062156272;6010.9699451502465;6085.269767627913;5661.773529058956;5741.799037033684;5820.854478638068;6055.303640081271;6062.454379105901 18:14:56 INFO - PID 30298 | |4;prototype.html;394.41429477226296;427.4251132227154;429.5003082062396;431.68702690549355;432.3721189720702;5935.4954828839545;5963.761449542018;5973.761049558023;5981.910271345933;5996.6402015879075;5749.511291540213;5859.121131830228;5864.530837532998;5871.970640146804;5879.088741245114;4630.351750754897;4652.488226295105;4668.439787225538;4672;4677.134730074947;4651.953480465186;4658.767061646921;4666.090112428061;4680.4149481314835;4682.578567928886;4532.750698711569;4634.910795962946;4655.185342565038;4663.60359369454;4703;4609.608183304423;4621.9306710399205;4631.494764201648;4633.698809577367;4678.929816052745;4176.791160441996;4182.707210495267;4204.789760511992;4217.768022758747;4228.429162063113;5613.607047506676;5762.4525670061275;5768.105943578752;5807.01280782268;5817.127430885359;5383.67697938125;5404.216388623628;5419.918701219445;5431.809886653988;5455.809046683347;6218.191635087455;6226.62640241587;6232.750689972396;6251.187345645082;6254.5607213186895;4621.306803979396;4623.12160689468;4676.95323046772;4717.976410117927;4730.692504987165;6199.163112979781;6365.968170159166;6384.361563843579;6469.5794773339585;6471.708773105222;4898.0366878038985;4907.214845624754;4908.190148625508;4920.3111564381015;4923.113839508923;4691;4926.876828079255;4990.126727822617;4990.276409920614;4997.875053123628;5314.5216930476445;5332.466753324637;5443.863903402367;5476.7535460904355;5481.75332110056;6250.6874656267;6253.78111766086;6258;6263.843403914939;6280.183576135073;1696.787901512311;1714.768506251665;1721.6040310728351;1723.8448539631493;1736.175316724571;1197.6047904191596;1222.3826967381415;1222.9327386993623;1229.1948773553336;1243.154654834721;5917.112433135064;5918.911216331759;5950.054722912099;5950.761969521257;5957.314908785458;6414.343824020357;6423.4861211103;6425.654636057899;6457.644829534327;6464.707058588301;5802.970985145132;5920.200772895604;5923.259592550931;5939.4951429129005;5941.524678025748;3373.645767194409;3404.114930118187;3408;3408.9602671185526;3415.334009868052;6478.740850365933;6626.171728533934;6642.601443913284;6651.534392592569;6720.495962802809;6538.542302038812;6550.7707230246715;6609.471242300604;6617.172853393326;6642.1033160523675;6524.575902566318;6650.235222949423;6681.231658359351;6711.563748356341;6715.932840671629;6396.168498095218;6404.263509696445;6407.647579383179;6413.903791443226;6434.678266086583;5896.351401345934;6021.668808215591;6351.491880649444;6362.840928976628;6365.649889256136;6552.934470655423;6631.270560238273;6668.899966500603;6683.699233534406;6726.831829204309;4915.11527924977;4920;4920.507949205051;4938.037082768969;4947.233178857283;6287.119803227461;6419.743210271537;6471.64405957658;6497.220333560003;6498.3826536478955;6222.564420490522;6249.875002500015;6256.436920677161;6259.6870156492905;6290.622562646256;5953.91069133972;6216.689165541795;6253.343398943045;6299.842503937438;6306.887192790283;6257.374262573889;6277.2153480814895;6283.214598175228;6287.685615719104;6312.905306420499;6246.531510136667;6294.8111556653375;6369.528606909153;6379.904301435389;6388.955534229002;6099.512039036777;6100.725467353891;6175.4750846176785;6177.135201071944;6180.289266734383 18:14:56 INFO - PID 30298 | |5;yui.html;762.9008228930238;764.5183534373344;772.7140957845598;772.8763397856344;773.744664260794;15948.920255398707;15980;16032.118233497153;16146.111963841999;16225.10761908096;14773.261336933137;14940.178290194035;14940.925295373536;14954.401823927057;15796;826.7974346285168;845.6194712379454;849.2950850793826;852.9189726975927;853.0190281176665;838.6687258532876;842.9241368276854;845.8815765792793;846.4328899637227;846.504794695101;885.1325700813206;888.8577827547593;889.168627333442;889.6574818694817;890.1676932068505;881.8280435315116;887.6183241206279;888.586807134679;888.9155530224618;890.995545022274;646.4634353486642;646.9967650161719;647.893097638889;650.1028580558799;653.539254825347;14465.349059292252;14749.115053096848;15841.683166336725;16770.916145419316;16845.241964111643;14514.419423223058;15291.158986255752;15461.226938653135;15785.447509337233;16013.279402426919;11887.821682674767;11889.346085965268;12001.33992630405;12188.634340969786;12307.015438764967;3171.4767063434506;3194.520821876737;3198.584184056081;3215.533747606584;3225.7903236289567;67548.6622566884;74573.99999999946;75995.62002190009;76502.6174869122;76944.61527692381;12052.216605920587;12287.877121228852;12383;12709.364531773374;12737.808932866015;24615.3846153844;24996.250112496655;25072;25076.623850642256;25149.24552263435;7923.643436045391;8014.398920081017;8071.23323284287;8293.626786794608;8581.528015959057;4158.7296825706235;4195.223883581547;4217.36739489073;4224.5353011168745;4337.566243375637;4286.142771445723;4323.524412314643;4334.089841133397;4350.58669426404;4381.255186618282;732.9853402931911;831.8710599857031;842.6208206307186;852.7271273192642;876.3120950054176;632.7532262417661;645.8740545593564;662.2417332154656;666.73997141115;677.701811203069;10813.18901082422;11157.163212759075;11348.659540213806;11381.886181138083;11390.544378224778;626.9153664255358;643.3759253524074;646.1825790375179;646.2406672160193;647.4852492268658;4870.659053866196;5091.872703182376;5113.194557944429;5122.692638441706;5152.948470515322;80864.59567702241;81607;82044.58977705192;82682.58658706667;85855.57072214599;53624.731876341146;66221.66889165524;66631;68735.31264687289;69161.65419172872;61349.38650613526;61661.38338616646;61949.380506194364;63475.682621586595;64467;10249.54060823954;10271;10485.580576776843;10528.631497897537;10532.104771094237;34387;34867.30265394627;35233.647663523036;35325;36200.27599448049;41723;41970;42156.78921605495;42169.78915105405;42737.5726242746;13004.934975325063;13053.216806991612;13127.463078698762;13169.209847409185;13255.138416002928;13364.866351336363;13376.398062087424;13442.865571344162;14140.50508232207;14337.211453369748;12788.744225115632;13324.800127998282;13396.531121410704;13662;13840.792388113985;13344.501662858624;13496.5276215332;13808.585742427744;13897.583072507841;13960.441582336593;12902;13039.934800325938;13184.472621095048;13361;13613.183209007491;9380.390274632127;9704.466254356079;10025.398476091457;10026.548805303924;10245;10712.785744285227;10747.785044299226;10850.186236032172;10858.511366988347;10872.130229581773 18:14:56 INFO - PID 30298 | __end_tp_report 18:14:56 INFO - PID 30298 | __start_cc_report 18:14:56 INFO - PID 30298 | _x_x_mozilla_cycle_collect,1192 18:14:56 INFO - PID 30298 | __end_cc_report 18:14:56 INFO - PID 30298 | __startTimestamp1490663696060__endTimestamp 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | ------- Summary: start ------- 18:14:56 INFO - PID 30298 | Number of tests: 6 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | [#0] dojo.html Cycles:165 Average:5754.26 Median:6020.94 stddev:797.61 (13.2%) stddev-sans-first:776.64 18:14:56 INFO - PID 30298 | Values: 3308.5 3319.4 3344.6 3362.8 3379.6 5709.7 5782.5 5834.1 5844.8 5866.3 5744.9 5808.7 5819.4 5827.8 5838.1 5163.9 5211.0 5235.6 5248.6 5287.4 5279.6 5295.1 5323.4 5370.7 5375.6 5288.8 5306.4 5309.7 5314.3 5318.3 362.7 4743.2 5173.7 5183.0 5301.7 5245.7 5266.9 5274.7 5290.7 5308.0 5802.9 5814.0 5837.2 5856.6 5865.7 6084.8 6095.7 6376.9 6382.1 6383.3 6185.4 6187.8 6209.5 6209.7 6256.8 5864.5 5869.8 5873.7 5874.0 5886.1 6348.8 6412.0 6443.7 6445.4 6455.8 5933.9 6056.0 6065.0 6089.6 6121.8 6271.9 6290.5 6297.1 6302.1 6305.5 5952.6 6010.1 6012.7 6123.8 6259.2 6190.6 6203.9 6207.9 6208.9 6212.6 4642.2 4681.5 4710.6 4720.8 4780.6 4194.3 4208.1 4223.0 4238.9 4256.7 5850.9 5871.3 5872.4 5873.4 5921.3 6108.4 6173.2 6274.5 6277.7 6316.4 5869.6 5895.8 5932.9 5933.2 5944.4 5313.9 5324.8 5326.3 5329.7 5332.2 6187.2 6539.7 6545.1 6548.6 6551.2 6204.5 6281.4 6295.0 6326.1 6329.2 6507.1 6521.9 6536.2 6544.4 6549.9 6124.8 6155.5 6160.1 6162.4 6166.0 6104.6 6226.8 6231.8 6259.7 6268.0 6036.6 6084.3 6084.7 6092.5 6119.3 5960.8 6041.7 6067.2 6092.1 6097.2 6012.7 6018.2 6020.9 6057.8 6087.3 6210.8 6216.4 6217.9 6220.9 6224.9 6040.7 6064.1 6064.5 6066.5 6118.7 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | [#1] ext.html Cycles:180 Average:42174.29 Median:32810.43 stddev:69445.81 (211.7%) stddev-sans-first:69570.49 18:14:56 INFO - PID 30298 | Values: 607.3 664.6 679.6 1134.8 1135.1 29504.1 36270.8 36945.8 37031.8 37035.1 39042.4 39057.8 39264.2 39369.8 39487.8 6027.1 6116.4 8224.5 8407.7 8413.1 7876.2 8035.6 8040.3 8050.8 8053.0 8369.5 8446.3 8470.2 8471.9 8483.2 8376.5 8419.7 8450.9 8456.3 8457.4 8240.0 8262.7 8305.4 8309.4 8320.5 40327.4 40559.6 40614.4 40637.0 40639.2 2987.3 2993.2 2999.1 3001.1 3036.1 27603.7 27812.6 28192.4 28373.3 28960.9 3657.6 3662.2 3711.1 3715.0 3718.1 201708.0 202152.0 202972.0 203209.0 203340.0 50018.0 50192.2 50404.0 50421.5 50455.7 53098.5 53171.0 53172.5 53221.7 53250.7 13309.3 13554.7 13595.0 13618.7 13630.9 9213.7 9290.3 10529.4 10625.5 10715.0 13246.5 13335.1 13399.2 13512.7 13533.1 5372.0 5375.2 5394.0 5544.3 5577.5 1029.0 1030.1 1031.2 1034.8 1045.9 25514.7 25812.4 25900.6 26023.2 26114.2 1123.8 1124.9 1127.8 1138.7 1165.1 12173.6 13171.4 13354.2 13504.4 13504.9 289436.0 289553.0 290383.0 291433.0 292052.0 55637.7 55663.7 55698.0 55812.4 55877.7 1423.4 1462.7 1464.0 1469.1 1472.0 23606.3 24070.3 25064.6 25155.0 25252.4 40196.8 40245.8 40405.0 40491.6 40619.0 292093.0 292488.0 292596.0 293162.0 293298.0 32696.5 33911.0 34010.3 34121.8 34257.8 41798.4 42043.8 42173.0 42402.8 43604.3 32172.0 32179.0 32257.0 32667.5 32924.3 31493.4 31565.8 31768.0 31816.2 32210.0 36966.6 37099.3 37629.1 37765.8 37847.8 27929.0 27990.9 28183.7 28310.7 28488.7 23556.8 25598.9 25684.9 26016.3 26048.9 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | [#2] jquery.html Cycles:180 Average:30127.37 Median:11184.77 stddev:85240.84 (762.1%) stddev-sans-first:85450.74 18:14:56 INFO - PID 30298 | Values: 400.5 412.5 428.5 434.1 434.2 15187.6 19401.3 19892.5 20037.1 20076.9 19684.1 20554.9 20705.5 21934.3 22128.0 5936.2 5941.2 6143.7 6152.6 6156.6 5923.2 6055.2 6055.7 6059.5 6059.8 5794.5 5972.5 6019.1 6023.8 6042.2 5935.3 5959.2 5986.4 5995.0 5998.3 5385.3 5410.1 5445.9 5458.4 5465.7 20349.4 20576.8 20823.3 25085.2 25164.4 1997.2 2002.9 2004.8 2008.9 2020.2 15463.5 16286.8 16498.5 16571.4 16866.3 1821.3 1826.4 1832.1 1851.1 1872.5 94237.0 95036.0 95231.0 96236.0 98286.5 10849.0 11875.4 12393.3 12493.6 12721.7 15894.2 17385.7 17865.3 17884.5 17887.8 8501.0 8581.0 8661.7 8838.1 8966.4 7109.0 7327.2 7424.1 7620.1 7839.5 7777.2 8029.2 8054.6 8067.7 8189.8 2266.5 2306.4 2338.7 2366.9 2387.2 1004.9 1014.8 1015.0 1018.3 1038.7 19413.2 19483.7 19736.2 19852.2 19960.6 1014.5 1032.0 1032.4 1036.3 1051.9 10206.7 10864.5 10889.7 10895.1 10929.9 247339.0 251685.0 612647.1 624180.0 651604.0 101429.0 101669.4 103828.0 104792.0 106648.0 1172.9 1207.4 1213.8 1221.1 1232.4 15950.7 16526.8 16821.7 17087.3 17233.1 46244.8 46441.3 46511.5 46517.8 47299.3 59708.4 59972.7 60979.7 61912.0 62706.4 9170.8 9230.4 9422.8 9568.2 9737.1 13767.5 13950.7 14320.0 14402.8 14410.4 10079.5 10400.5 11795.4 12394.4 12588.4 11125.3 11352.3 11500.7 11583.8 11638.1 12629.7 12634.9 12909.7 13027.5 13114.9 9474.3 9529.3 9580.1 9635.2 9658.7 11971.0 12035.3 12057.3 12322.0 12693.5 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | [#3] mootools.html Cycles:180 Average:6836.15 Median:2382.28 stddev:4317.60 (181.2%) stddev-sans-first:4309.58 18:14:56 INFO - PID 30298 | Values: 1287.7 1316.9 1320.7 1332.6 1338.0 10382.9 10844.2 10896.0 10897.2 10901.5 9899.5 10147.2 10148.8 10221.5 10693.9 2303.0 2332.2 2337.0 2338.2 2342.6 1987.8 2162.7 2200.4 2208.0 2271.1 2039.8 2307.8 2381.0 2383.5 2384.0 2251.5 2311.7 2313.8 2344.5 2344.8 2242.1 2248.6 2258.2 2261.8 2266.3 10243.4 10281.5 10369.5 10370.5 10399.1 5828.7 6010.4 6014.2 6017.3 6022.3 3689.8 3719.2 3794.2 3808.0 3815.4 1369.6 1860.6 1897.5 1916.2 1961.6 15834.5 16126.6 16134.3 16138.5 16165.4 5103.5 5180.2 5184.5 5197.5 5227.0 12099.9 12102.6 12117.9 12137.2 12159.1 6163.8 6163.8 6167.0 6175.6 6178.1 5597.1 5597.2 5632.2 5703.5 5707.3 4052.6 4145.0 4161.5 4164.5 4176.1 1067.4 1098.7 1179.8 1187.0 1196.7 807.6 824.2 836.2 850.7 854.9 6149.8 6167.6 6264.9 6271.2 6371.3 915.9 921.8 936.0 943.2 948.2 4009.1 4566.4 4606.9 4800.5 4915.8 13758.2 14045.4 14126.3 14147.2 14169.8 13700.4 14092.1 14098.2 14246.1 14282.1 10194.8 10354.3 10365.6 10428.8 10473.9 6226.8 6456.8 6499.8 6598.6 6849.3 12046.6 12675.2 12824.2 13480.8 13524.4 12718.7 13023.0 13387.8 13403.9 13620.5 9459.5 9492.4 9592.3 9671.1 9704.0 9760.0 10291.3 10502.6 10534.5 10542.5 8445.4 8486.6 8537.7 8729.7 8800.5 8419.5 8609.8 8748.1 8783.6 8976.1 7128.9 7178.2 7253.3 7253.5 7255.1 5926.7 5950.8 5989.6 6011.0 6085.3 5661.8 5741.8 5820.9 6055.3 6062.5 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | [#4] prototype.html Cycles:180 Average:5315.47 Median:5929.38 stddev:1509.81 (25.5%) stddev-sans-first:1468.17 18:14:56 INFO - PID 30298 | Values: 394.4 427.4 429.5 431.7 432.4 5935.5 5963.8 5973.8 5981.9 5996.6 5749.5 5859.1 5864.5 5872.0 5879.1 4630.4 4652.5 4668.4 4672.0 4677.1 4652.0 4658.8 4666.1 4680.4 4682.6 4532.8 4634.9 4655.2 4663.6 4703.0 4609.6 4621.9 4631.5 4633.7 4678.9 4176.8 4182.7 4204.8 4217.8 4228.4 5613.6 5762.5 5768.1 5807.0 5817.1 5383.7 5404.2 5419.9 5431.8 5455.8 6218.2 6226.6 6232.8 6251.2 6254.6 4621.3 4623.1 4677.0 4718.0 4730.7 6199.2 6366.0 6384.4 6469.6 6471.7 4898.0 4907.2 4908.2 4920.3 4923.1 4691.0 4926.9 4990.1 4990.3 4997.9 5314.5 5332.5 5443.9 5476.8 5481.8 6250.7 6253.8 6258.0 6263.8 6280.2 1696.8 1714.8 1721.6 1723.8 1736.2 1197.6 1222.4 1222.9 1229.2 1243.2 5917.1 5918.9 5950.1 5950.8 5957.3 6414.3 6423.5 6425.7 6457.6 6464.7 5803.0 5920.2 5923.3 5939.5 5941.5 3373.6 3404.1 3408.0 3409.0 3415.3 6478.7 6626.2 6642.6 6651.5 6720.5 6538.5 6550.8 6609.5 6617.2 6642.1 6524.6 6650.2 6681.2 6711.6 6715.9 6396.2 6404.3 6407.6 6413.9 6434.7 5896.4 6021.7 6351.5 6362.8 6365.6 6552.9 6631.3 6668.9 6683.7 6726.8 4915.1 4920.0 4920.5 4938.0 4947.2 6287.1 6419.7 6471.6 6497.2 6498.4 6222.6 6249.9 6256.4 6259.7 6290.6 5953.9 6216.7 6253.3 6299.8 6306.9 6257.4 6277.2 6283.2 6287.7 6312.9 6246.5 6294.8 6369.5 6379.9 6389.0 6099.5 6100.7 6175.5 6177.1 6180.3 18:14:56 INFO - PID 30298 | 18:14:56 INFO - PID 30298 | [#5] yui.html Cycles:180 Average:17083.75 Median:23082.61 stddev:21419.37 (92.8%) stddev-sans-first:21444.39 18:14:56 INFO - PID 30298 | Values: 762.9 764.5 772.7 772.9 773.7 15948.9 15980.0 16032.1 16146.1 16225.1 14773.3 14940.2 14940.9 14954.4 15796.0 826.8 845.6 849.3 852.9 853.0 838.7 842.9 845.9 846.4 846.5 885.1 888.9 889.2 889.7 890.2 881.8 887.6 888.6 888.9 891.0 646.5 647.0 647.9 650.1 653.5 14465.3 14749.1 15841.7 16770.9 16845.2 14514.4 15291.2 15461.2 15785.4 16013.3 11887.8 11889.3 12001.3 12188.6 12307.0 3171.5 3194.5 3198.6 3215.5 3225.8 67548.7 74574.0 75995.6 76502.6 76944.6 12052.2 12287.9 12383.0 12709.4 12737.8 24615.4 24996.3 25072.0 25076.6 25149.2 7923.6 8014.4 8071.2 8293.6 8581.5 4158.7 4195.2 4217.4 4224.5 4337.6 4286.1 4323.5 4334.1 4350.6 4381.3 733.0 831.9 842.6 852.7 876.3 632.8 645.9 662.2 666.7 677.7 10813.2 11157.2 11348.7 11381.9 11390.5 626.9 643.4 646.2 646.2 647.5 4870.7 5091.9 5113.2 5122.7 5152.9 80864.6 81607.0 82044.6 82682.6 85855.6 53624.7 66221.7 66631.0 68735.3 69161.7 61349.4 61661.4 61949.4 63475.7 64467.0 10249.5 10271.0 10485.6 10528.6 10532.1 34387.0 34867.3 35233.6 35325.0 36200.3 41723.0 41970.0 42156.8 42169.8 42737.6 13004.9 13053.2 13127.5 13169.2 13255.1 13364.9 13376.4 13442.9 14140.5 14337.2 12788.7 13324.8 13396.5 13662.0 13840.8 13344.5 13496.5 13808.6 13897.6 13960.4 12902.0 13039.9 13184.5 13361.0 13613.2 9380.4 9704.5 10025.4 10026.5 10245.0 10712.8 10747.8 10850.2 10858.5 10872.1 18:14:56 INFO - PID 30298 | -------- Summary: end -------- 18:14:56 INFO - PID 30298 | 18:14:56 INFO - TEST-INFO | 30298: exit 0 18:14:56 INFO - TEST-OK | dromaeo_css | took 1115699ms 18:14:56 INFO - TEST-START | kraken 18:14:56 INFO - operating with platform_type : linux_ 18:14:56 INFO - Initialising browser for kraken test... 18:14:56 INFO - TEST-INFO | started process 30621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0gnec_/profile http://localhost:40219/getInfo.html) 18:15:00 INFO - PID 30621 | __metrics Screen width/height:1600/1200 18:15:00 INFO - PID 30621 | colorDepth:24 18:15:00 INFO - PID 30621 | Browser inner width/height: 1024/697 18:15:00 INFO - PID 30621 | __metrics 18:15:01 ERROR - PID 30621 | 1490663701342 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:15:01 INFO - PID 30621 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:15:01 INFO - PID 30621 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:15:02 INFO - TEST-INFO | 30621: exit 0 18:15:02 INFO - Browser initialized. 18:15:02 INFO - Running cycle 1/1 for kraken test... 18:15:02 INFO - TEST-INFO | started process 30716 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0gnec_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:14 INFO - PID 30716 | RSS: Main: 145940480 18:15:14 INFO - PID 30716 | 18:15:17 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40219/tests/kraken/driver.html?testName=audio-beat-detection) 18:15:17 INFO - PID 30716 | RSS: Main: 201637888 18:15:17 INFO - PID 30716 | 18:15:20 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40219/tests/kraken/driver.html?testName=audio-dft) 18:15:21 INFO - PID 30716 | RSS: Main: 180928512 18:15:21 INFO - PID 30716 | 18:15:24 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40219/tests/kraken/driver.html?testName=audio-fft) 18:15:24 INFO - PID 30716 | RSS: Main: 262680576 18:15:24 INFO - PID 30716 | 18:15:26 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40219/tests/kraken/driver.html?testName=audio-oscillator) 18:15:27 INFO - PID 30716 | RSS: Main: 172593152 18:15:27 INFO - PID 30716 | 18:15:29 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:15:30 INFO - PID 30716 | RSS: Main: 176943104 18:15:30 INFO - PID 30716 | 18:15:33 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40219/tests/kraken/driver.html?testName=imaging-darkroom) 18:15:33 INFO - PID 30716 | RSS: Main: 215719936 18:15:33 INFO - PID 30716 | 18:15:37 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40219/tests/kraken/driver.html?testName=imaging-desaturate) 18:15:37 INFO - PID 30716 | RSS: Main: 224112640 18:15:37 INFO - PID 30716 | 18:15:40 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40219/tests/kraken/driver.html?testName=json-parse-financial) 18:15:41 INFO - PID 30716 | RSS: Main: 230535168 18:15:41 INFO - PID 30716 | 18:15:42 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:15:43 INFO - PID 30716 | RSS: Main: 178978816 18:15:43 INFO - PID 30716 | 18:15:45 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:15:45 INFO - PID 30716 | RSS: Main: 186179584 18:15:45 INFO - PID 30716 | 18:15:47 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:15:48 INFO - PID 30716 | RSS: Main: 194539520 18:15:48 INFO - PID 30716 | 18:15:51 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:15:52 INFO - PID 30716 | RSS: Main: 203026432 18:15:52 INFO - PID 30716 | 18:15:55 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:15:55 INFO - PID 30716 | RSS: Main: 200658944 18:15:55 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | Cycle 1(1): loaded http://localhost:40219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40219/tests/kraken/driver.html?testName=ai-astar) 18:15:57 INFO - PID 30716 | RSS: Main: 268148736 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | __start_tp_report 18:15:57 INFO - PID 30716 | _x_x_mozilla_page_load 18:15:57 INFO - PID 30716 | _x_x_mozilla_page_load_details 18:15:57 INFO - PID 30716 | |i|pagename|runs| 18:15:57 INFO - PID 30716 | |0;ai-astar;102;111;112;110;111;111;113;111;113;111 18:15:57 INFO - PID 30716 | |1;audio-beat-detection;154;120;120;169;121;121;119;119;146;122 18:15:57 INFO - PID 30716 | |2;audio-dft;163;164;165;164;165;164;167;164;165;164 18:15:57 INFO - PID 30716 | |3;audio-fft;75;76;76;108;76;75;75;77;99;74 18:15:57 INFO - PID 30716 | |4;audio-oscillator;84;84;106;84;83;84;101;84;84;105 18:15:57 INFO - PID 30716 | |5;imaging-gaussian-blur;114;113;114;113;114;114;114;115;113;114 18:15:57 INFO - PID 30716 | |6;imaging-darkroom;148;148;148;148;149;149;149;149;149;148 18:15:57 INFO - PID 30716 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;94 18:15:57 INFO - PID 30716 | |8;json-parse-financial;59;59;57;57;59;59;59;59;59;58 18:15:57 INFO - PID 30716 | |9;json-stringify-tinderbox;55;55;54;55;55;54;56;58;55;55 18:15:57 INFO - PID 30716 | |10;stanford-crypto-aes;81;79;82;80;80;81;81;81;80;81 18:15:57 INFO - PID 30716 | |11;stanford-crypto-ccm;129;132;141;146;144;127;136;129;176;129 18:15:57 INFO - PID 30716 | |12;stanford-crypto-pbkdf2;186;183;190;185;184;187;185;183;190;186 18:15:57 INFO - PID 30716 | |13;stanford-crypto-sha256-iterative;66;64;66;71;65;66;75;65;68;64 18:15:57 INFO - PID 30716 | __end_tp_report 18:15:57 INFO - PID 30716 | __start_cc_report 18:15:57 INFO - PID 30716 | _x_x_mozilla_cycle_collect,1711 18:15:57 INFO - PID 30716 | __end_cc_report 18:15:57 INFO - PID 30716 | __startTimestamp1490663757629__endTimestamp 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | ------- Summary: start ------- 18:15:57 INFO - PID 30716 | Number of tests: 14 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 18:15:57 INFO - PID 30716 | Values: 102.0 111.0 112.0 110.0 111.0 111.0 113.0 111.0 113.0 111.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:121.50 stddev:18.28 (15.0%) stddev-sans-first:17.41 18:15:57 INFO - PID 30716 | Values: 154.0 120.0 120.0 169.0 121.0 121.0 119.0 119.0 146.0 122.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.50 stddev:1.08 (0.7%) stddev-sans-first:1.00 18:15:57 INFO - PID 30716 | Values: 163.0 164.0 165.0 164.0 165.0 164.0 167.0 164.0 165.0 164.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:12.02 (15.8%) stddev-sans-first:12.55 18:15:57 INFO - PID 30716 | Values: 75.0 76.0 76.0 108.0 76.0 75.0 75.0 77.0 99.0 74.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:84.00 stddev:9.81 (11.7%) stddev-sans-first:10.17 18:15:57 INFO - PID 30716 | Values: 84.0 84.0 106.0 84.0 83.0 84.0 101.0 84.0 84.0 105.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 18:15:57 INFO - PID 30716 | Values: 114.0 113.0 114.0 113.0 114.0 114.0 114.0 115.0 113.0 114.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 18:15:57 INFO - PID 30716 | Values: 148.0 148.0 148.0 148.0 149.0 149.0 149.0 149.0 149.0 148.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 18:15:57 INFO - PID 30716 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:59.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 18:15:57 INFO - PID 30716 | Values: 59.0 59.0 57.0 57.0 59.0 59.0 59.0 59.0 59.0 58.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:1.14 (2.1%) stddev-sans-first:1.20 18:15:57 INFO - PID 30716 | Values: 55.0 55.0 54.0 55.0 55.0 54.0 56.0 58.0 55.0 55.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:81.00 stddev:0.84 (1.0%) stddev-sans-first:0.88 18:15:57 INFO - PID 30716 | Values: 81.0 79.0 82.0 80.0 80.0 81.0 81.0 81.0 80.0 81.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:138.50 stddev:14.72 (10.6%) stddev-sans-first:15.17 18:15:57 INFO - PID 30716 | Values: 129.0 132.0 141.0 146.0 144.0 127.0 136.0 129.0 176.0 129.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:186.00 stddev:2.51 (1.4%) stddev-sans-first:2.67 18:15:57 INFO - PID 30716 | Values: 186.0 183.0 190.0 185.0 184.0 187.0 185.0 183.0 190.0 186.0 18:15:57 INFO - PID 30716 | 18:15:57 INFO - PID 30716 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.00 stddev:3.50 (5.3%) stddev-sans-first:3.69 18:15:57 INFO - PID 30716 | Values: 66.0 64.0 66.0 71.0 65.0 66.0 75.0 65.0 68.0 64.0 18:15:57 INFO - PID 30716 | -------- Summary: end -------- 18:15:57 INFO - PID 30716 | 18:15:58 INFO - TEST-INFO | 30716: exit 0 18:15:58 INFO - TEST-OK | kraken | took 61811ms 18:15:58 INFO - SUITE-END | took 1177s 18:15:58 INFO - Completed test suite (00:19:37) 18:15:58 INFO - javascript benchmark 18:15:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7501.761263588063, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.4871844864037, 3319.369319829234, 3344.632090470049, 3362.764606477546, 3379.5944486661588, 5709.71451427429, 5782.45066718661, 5834.066549352105, 5844.766209351632, 5866.266716660418, 5744.856378590537, 5808.680522571257, 5819.418058194178, 5827.766889324433, 5838.095095260231, 5163.896722065566, 5210.973945130269, 5235.607329450288, 5248.632595718302, 5287.41838397776, 5279.5512381447625, 5295.126304159809, 5323.441038690939, 5370.7046112463795, 5375.596830237729, 5288.762005709752, 5306.416294207634, 5309.707966061865, 5314.309139811839, 5318.25544423781, 362.7330525098805, 4743.193657078305, 5173.715445650488, 5183.041137389595, 5301.7084060376665, 5245.711485868275, 5266.8683282917855, 5274.709890955995, 5290.709011004393, 5307.973460132714, 5802.88394232113, 5814.0, 5837.182794408786, 5856.648601083949, 5865.736041878125, 6084.787032453887, 6095.710389288371, 6376.915924292882, 6382.1384113144595, 6383.32975037619, 6185.41238582334, 6187.81436556904, 6209.534284928648, 6209.720562574695, 6256.812295631138, 5864.472197502245, 5869.8239052828485, 5873.706314684291, 5873.970630146908, 5886.1465087562065, 6348.841278968062, 6411.96794016027, 6443.742250310029, 6445.355464453611, 6455.838604034842, 5933.9406605934255, 6056.0, 6065.0, 6089.604175728564, 6121.84695382619, 6271.905921411182, 6290.465310448663, 6297.149884765498, 6302.1492098566105, 6305.495560355161, 5952.5833191676165, 6010.128531362928, 6012.729427175787, 6123.785667501706, 6259.155014073133, 6190.566660333733, 6203.875922481525, 6207.875842483125, 6208.906866397007, 6212.565120441525, 4642.1644104060915, 4681.531846815359, 4710.552497512731, 4720.763961801897, 4780.617550595944, 4194.2869712149, 4208.116295577902, 4222.9577704223175, 4238.936415953764, 4256.702030857872, 5850.882982340415, 5871.266091738533, 5872.412758724094, 5873.383294754075, 5921.348651648315, 6108.372425856702, 6173.166622505904, 6274.466670332981, 6277.7175027123885, 6316.399942005502, 5869.559783016291, 5895.793647222326, 5932.851678707981, 5933.228680271537, 5944.375840536768, 5313.867153321121, 5324.7870085196155, 5326.2543243945875, 5329.733513324319, 5332.173513105475, 6187.195664563578, 6539.673016349068, 6545.05096760967, 6548.607083575001, 6551.181102362205, 6204.472619827366, 6281.434670879643, 6294.968525157345, 6326.082718005771, 6329.240491141092, 6507.12153859223, 6521.902171467526, 6536.150300460909, 6544.411003009752, 6549.934500654933, 6124.846878827887, 6155.507559395327, 6160.13758073861, 6162.414570615784, 6166.044263139221, 6104.641056839769, 6226.782062627967, 6231.813045608639, 6259.655718935503, 6268.0, 6036.63780173191, 6084.300305464928, 6084.665343406155, 6092.482139018233, 6119.296280927572, 5960.821175364746, 6041.69791510414, 6067.24159480065, 6092.147099406176, 6097.237845269341, 6012.669303188367, 6018.1875446815875, 6020.939790602038, 6057.818265452043, 6087.269527656709, 6210.7515699371515, 6216.440520353009, 6217.906731399123, 6220.875582488416, 6224.9377506224355, 6040.728167232397, 6064.120702498025, 6064.514838812973, 6066.514678825595, 6118.724657390517], "value": 5700.178669920127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.3076692570468, 664.6377724140343, 679.5922446532081, 1134.8467956825832, 1135.1089394825053, 29504.11487655371, 36270.818645906766, 36945.81527092361, 37031.814840925756, 37035.0741231469, 39042.41436378456, 39057.80471097641, 39264.21471570567, 39369.803150984204, 39487.80256098723, 6027.0658048002415, 6116.357782432825, 8224.547649879254, 8407.663693452254, 8413.116622754615, 7876.173001834811, 8035.598220088972, 8040.276375126237, 8050.758477245663, 8052.959735201316, 8369.455985360932, 8446.282066024396, 8470.195331443503, 8471.872921906177, 8483.194096560817, 8376.455530390505, 8419.663213471455, 8450.873236901421, 8456.323494120456, 8457.365697572706, 8240.0, 8262.669493220264, 8305.418620696553, 8309.37679674021, 8320.542370169638, 40327.39508907369, 40559.594404055875, 40614.39078413826, 40637.0, 40639.18721625581, 2987.268119310783, 2993.236724635226, 2999.1302522268516, 3001.0996700989836, 3036.104349216986, 27603.723962760316, 27812.582811257846, 28192.43615127686, 28373.290667733265, 28960.855195723885, 3657.6159503252306, 3662.249238906018, 3711.0907827582414, 3715.0, 3718.0518967663074, 201708.0, 202152.0, 202972.0, 203209.0, 203340.0, 50018.0, 50192.247116293285, 50404.0, 50421.49578504241, 50455.747721261156, 53098.469015310126, 53171.0, 53172.46827531675, 53221.733891330296, 53250.73374633102, 13309.267990260434, 13554.661133471742, 13595.0, 13618.727625447436, 13630.863691363158, 9213.710080588722, 9290.256779457628, 10529.36823790575, 10625.46872656364, 10715.0, 13246.536371226961, 13335.133216340906, 13399.196048237138, 13512.729745405037, 13533.120347177402, 5371.973140134274, 5375.247465354853, 5393.9730301348245, 5544.3346798384455, 5577.470140336662, 1028.99485502572, 1030.108955753268, 1031.242037102729, 1034.798214348195, 1045.8744950605976, 25514.74485255161, 25812.354691132496, 25900.611490827654, 26023.219303420927, 26114.216573502825, 1123.8370436286857, 1124.8875112488686, 1127.7518945831903, 1138.7267055906523, 1165.0892536240572, 12173.634790956285, 13171.407286672124, 13354.198748075147, 13504.392302346418, 13504.93247533756, 289436.0, 289553.0, 290383.0, 291433.0, 292052.0, 55637.72181139149, 55663.72168139133, 55698.0, 55812.44187558073, 55877.7206113975, 1423.366601862182, 1462.7074585082812, 1463.992680036593, 1469.1478942213491, 1472.0211059645217, 23606.29181124569, 24070.277891663278, 25064.62403063992, 25155.0, 25252.368690782878, 40196.799016004734, 40245.79877100596, 40405.0, 40491.595084048786, 40619.0, 292093.0, 292488.0, 292596.0, 293162.0, 293298.0, 32696.50955235626, 33911.0, 34010.31979360448, 34121.82939085289, 34257.82871085629, 41798.373024405264, 42043.7897810509, 42173.0, 42402.78798606111, 43604.34593481037, 32172.0, 32179.0, 32257.0, 32667.509987350684, 32924.34151317008, 31493.370132597676, 31565.842170788997, 31768.0, 31816.204594885312, 32209.97375328084, 36966.630333697394, 37099.25801484009, 37629.05927351838, 37765.81117094397, 37847.81076094602, 27929.0, 27990.860045699643, 28183.71816281811, 28310.71689283163, 28488.715112848608, 23556.764432356144, 25598.872005639852, 25684.871575642002, 26016.34959126037, 26048.8697556511], "value": 17211.127065516852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.5093760143822, 412.4885142423396, 428.4601402233186, 434.11007434758744, 434.2270758050669, 15187.620309492268, 19401.320953766604, 19892.50268743282, 20037.09833057512, 20076.8996155019, 19684.11421486033, 20554.89722551389, 20705.482362940933, 21934.341969740894, 22128.0, 5936.198613187229, 5941.197938278342, 6143.662098584598, 6152.630842149485, 6156.599821011642, 5923.229980102581, 6055.2430946131735, 6055.727492262837, 6059.515238780887, 6059.848503787419, 5794.5074668653215, 5972.462478376945, 6019.067044608071, 6023.759049638009, 6042.154098426224, 5935.317438494563, 5959.195508606348, 5986.401359864022, 5995.0, 5998.25021872266, 5385.272988146589, 5410.1343784994215, 5445.891082178374, 5458.426865179139, 5465.672059676433, 20349.389518314474, 20576.794232057637, 20823.271185508587, 25085.24744257675, 25164.370890727696, 1997.2310660395708, 2002.9499262518407, 2004.789497102798, 2008.8593798434115, 2020.1515363547346, 15463.536093917088, 16286.83713162865, 16498.505044848673, 16571.42000029993, 16866.28941632839, 1821.2623887325653, 1826.4155470249525, 1832.0931139086238, 1851.139220262571, 1872.5253788217158, 94237.0, 95036.0, 95231.0, 96236.0, 98286.50856745814, 10849.0, 11875.40622968848, 12393.318367489694, 12493.625191244277, 12721.745565088646, 15894.205289735699, 17385.652286954442, 17865.285388584573, 17884.463466096036, 17887.821121788875, 8500.957495212608, 8581.0, 8661.696840610548, 8838.116188381111, 8966.386050510971, 7108.964455177691, 7327.157376901618, 7424.071991001125, 7620.123685776096, 7839.529628222325, 7777.1833957433655, 8029.237222463857, 8054.59727013647, 8067.677292908335, 8189.829695130795, 2266.46738016566, 2306.434923443767, 2338.731045929706, 2366.9053237870294, 2387.1644924276366, 1004.9045340692622, 1014.8071866345371, 1014.9898501014895, 1018.3431686562126, 1038.7091614347994, 19413.223471061283, 19483.707744383846, 19736.210551578064, 19852.205911763656, 19960.60078798445, 1014.4826138669332, 1031.9845202321972, 1032.4476405123223, 1036.3471013261596, 1051.9053285204368, 10206.693799186036, 10864.511097000654, 10889.673309800719, 10895.128389728803, 10929.945350273358, 247339.0, 251685.0, 612647.0514711414, 624180.0, 651604.0, 101429.0, 101669.44156954471, 103828.0, 104792.0, 106648.0, 1172.935488548121, 1207.3963018490754, 1213.805791073424, 1221.126894270583, 1232.371490539831, 15950.650363423347, 16526.83473165253, 16821.74767378466, 17087.316507340063, 17233.138343082544, 46244.76877615591, 46441.30338044865, 46511.53488465072, 46517.76741116273, 47299.290510643055, 59708.402915970284, 59972.700136499036, 60979.69510152421, 61912.0, 62706.37293627188, 9170.816583668422, 9230.400023998418, 9422.811543768948, 9568.186704130227, 9737.07497787709, 13767.518136865161, 13950.720985580028, 14320.0, 14402.775764060172, 14410.351534180778, 10079.496025198858, 10400.531976061247, 11795.410229488663, 12394.442250098948, 12588.363512743284, 11125.332480051224, 11352.318860868374, 11500.654980350602, 11583.8262426062, 11638.12714046433, 12629.74740505203, 12634.873651263737, 12909.677258068623, 13027.544035958696, 13114.868851311747, 9474.289428293043, 9529.332946693943, 9580.13778759915, 9635.181009614262, 9658.696076029646, 11971.0, 12035.277883327028, 12057.336846473529, 12322.0, 12693.492260309484], "value": 9885.52767751772, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.6716437308485, 1316.8946484281253, 1320.7160460500997, 1332.640187149469, 1338.0432990411853, 10382.896171038288, 10844.186685998562, 10896.019358257756, 10897.237193396466, 10901.454927253635, 9899.50502474877, 10147.238957078212, 10148.847767283478, 10221.540030698618, 10693.946530267358, 2303.0442366417915, 2332.2303639798824, 2336.976630233693, 2338.205010296499, 2342.5666251743482, 1987.761468623767, 2162.697222388867, 2200.4058904095887, 2207.9889600551974, 2271.114265436473, 2039.7654269759018, 2307.8038366738847, 2381.047580967618, 2383.5113801670705, 2383.976160238393, 2251.549690061978, 2311.687922130516, 2313.7801908818637, 2344.5310937812505, 2344.753800850912, 2242.058335499078, 2248.640217565198, 2258.2096266306826, 2261.8416710830247, 2266.29744779119, 10243.385396876136, 10281.48592570376, 10369.481525923673, 10370.533326000274, 10399.116075133623, 5828.708564571754, 6010.398960103955, 6014.188084608566, 6017.338092809788, 6022.277326720778, 3689.7786132831848, 3719.2375563009787, 3794.165283637595, 3808.0, 3815.4467602197524, 1369.5754316162022, 1860.6185731924922, 1897.5256185953513, 1916.2047750183658, 1961.5880665060354, 15834.524964251092, 16126.596835079099, 16134.273957671932, 16138.515844524683, 16165.434209802717, 5103.540681338697, 5180.248863914711, 5184.455632158644, 5197.532222100029, 5226.973865130726, 12099.939500302442, 12102.636920892388, 12117.87882121185, 12137.150399471951, 12159.088068394729, 6163.753449861955, 6163.815085547351, 6167.044108163242, 6175.5677102602385, 6178.1350610913605, 5597.104463285855, 5597.216389705444, 5632.155176723442, 5703.458171473703, 5707.286589176353, 4052.5542190359038, 4145.0, 4161.500619925568, 4164.500259968823, 4176.102138040336, 1067.3969207397795, 1098.653924013933, 1179.7876382251277, 1186.9821952670716, 1196.6888608961733, 807.5518087461516, 824.1922915542801, 836.2445741282489, 850.7405241401308, 854.9145085491401, 6149.84625384369, 6167.568270221132, 6264.906026409608, 6271.153394291803, 6371.324043901573, 915.8946721127155, 921.8432866412726, 935.9812803743887, 943.2218419803689, 948.2082461145021, 4009.1179940413062, 4566.383538222298, 4606.930896036562, 4800.4719480857075, 4915.778789954461, 13758.243296618582, 14045.367958441895, 14126.32715493979, 14147.151170929777, 14169.78745318821, 13700.3834827433, 14092.084014539023, 14098.224597647022, 14246.145231286157, 14282.07166534172, 10194.847077293847, 10354.326968747007, 10365.585376585002, 10428.791424171473, 10473.895261047444, 6226.750929962751, 6456.774012909714, 6499.772507962199, 6598.571092878948, 6849.315068493111, 12046.638600841989, 12675.239485630891, 12824.23054616726, 13480.79778803299, 13524.391402387113, 12718.68203294888, 13023.0, 13387.799183012068, 13403.865961340653, 13620.455181793013, 9459.479728614991, 9492.382995105296, 9592.328537002342, 9671.129598336183, 9703.951480242553, 9759.951200243953, 10291.279610427198, 10502.632407865687, 10534.473276335999, 10542.472876356305, 8445.366597505088, 8486.61810218554, 8537.74386768398, 8729.65081396737, 8800.47197168172, 8419.494830310201, 8609.82780344402, 8748.1251874812, 8783.648654053766, 8976.102389760972, 7128.928710712827, 7178.21168986256, 7253.310935461122, 7253.528520646141, 7255.129384473897, 5926.733297001731, 5950.7917222896995, 5989.64062156272, 6010.9699451502465, 6085.269767627913, 5661.773529058956, 5741.799037033684, 5820.854478638068, 6055.303640081271, 6062.454379105901], "value": 5198.553174693162, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.41429477226296, 427.4251132227154, 429.5003082062396, 431.68702690549355, 432.3721189720702, 5935.4954828839545, 5963.761449542018, 5973.761049558023, 5981.910271345933, 5996.6402015879075, 5749.511291540213, 5859.121131830228, 5864.530837532998, 5871.970640146804, 5879.088741245114, 4630.351750754897, 4652.488226295105, 4668.439787225538, 4672.0, 4677.134730074947, 4651.953480465186, 4658.767061646921, 4666.090112428061, 4680.4149481314835, 4682.578567928886, 4532.750698711569, 4634.910795962946, 4655.185342565038, 4663.60359369454, 4703.0, 4609.608183304423, 4621.9306710399205, 4631.494764201648, 4633.698809577367, 4678.929816052745, 4176.791160441996, 4182.707210495267, 4204.789760511992, 4217.768022758747, 4228.429162063113, 5613.607047506676, 5762.4525670061275, 5768.105943578752, 5807.01280782268, 5817.127430885359, 5383.67697938125, 5404.216388623628, 5419.918701219445, 5431.809886653988, 5455.809046683347, 6218.191635087455, 6226.62640241587, 6232.750689972396, 6251.187345645082, 6254.5607213186895, 4621.306803979396, 4623.12160689468, 4676.95323046772, 4717.976410117927, 4730.692504987165, 6199.163112979781, 6365.968170159166, 6384.361563843579, 6469.5794773339585, 6471.708773105222, 4898.0366878038985, 4907.214845624754, 4908.190148625508, 4920.3111564381015, 4923.113839508923, 4691.0, 4926.876828079255, 4990.126727822617, 4990.276409920614, 4997.875053123628, 5314.5216930476445, 5332.466753324637, 5443.863903402367, 5476.7535460904355, 5481.75332110056, 6250.6874656267, 6253.78111766086, 6258.0, 6263.843403914939, 6280.183576135073, 1696.787901512311, 1714.768506251665, 1721.6040310728351, 1723.8448539631493, 1736.175316724571, 1197.6047904191596, 1222.3826967381415, 1222.9327386993623, 1229.1948773553336, 1243.154654834721, 5917.112433135064, 5918.911216331759, 5950.054722912099, 5950.761969521257, 5957.314908785458, 6414.343824020357, 6423.4861211103, 6425.654636057899, 6457.644829534327, 6464.707058588301, 5802.970985145132, 5920.200772895604, 5923.259592550931, 5939.4951429129005, 5941.524678025748, 3373.645767194409, 3404.114930118187, 3408.0, 3408.9602671185526, 3415.334009868052, 6478.740850365933, 6626.171728533934, 6642.601443913284, 6651.534392592569, 6720.495962802809, 6538.542302038812, 6550.7707230246715, 6609.471242300604, 6617.172853393326, 6642.1033160523675, 6524.575902566318, 6650.235222949423, 6681.231658359351, 6711.563748356341, 6715.932840671629, 6396.168498095218, 6404.263509696445, 6407.647579383179, 6413.903791443226, 6434.678266086583, 5896.351401345934, 6021.668808215591, 6351.491880649444, 6362.840928976628, 6365.649889256136, 6552.934470655423, 6631.270560238273, 6668.899966500603, 6683.699233534406, 6726.831829204309, 4915.11527924977, 4920.0, 4920.507949205051, 4938.037082768969, 4947.233178857283, 6287.119803227461, 6419.743210271537, 6471.64405957658, 6497.220333560003, 6498.3826536478955, 6222.564420490522, 6249.875002500015, 6256.436920677161, 6259.6870156492905, 6290.622562646256, 5953.91069133972, 6216.689165541795, 6253.343398943045, 6299.842503937438, 6306.887192790283, 6257.374262573889, 6277.2153480814895, 6283.214598175228, 6287.685615719104, 6312.905306420499, 6246.531510136667, 6294.8111556653375, 6369.528606909153, 6379.904301435389, 6388.955534229002, 6099.512039036777, 6100.725467353891, 6175.4750846176785, 6177.135201071944, 6180.289266734383], "value": 4857.979127269921, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.9008228930238, 764.5183534373344, 772.7140957845598, 772.8763397856344, 773.744664260794, 15948.920255398707, 15980.0, 16032.118233497153, 16146.111963841999, 16225.10761908096, 14773.261336933137, 14940.178290194035, 14940.925295373536, 14954.401823927057, 15796.0, 826.7974346285168, 845.6194712379454, 849.2950850793826, 852.9189726975927, 853.0190281176665, 838.6687258532876, 842.9241368276854, 845.8815765792793, 846.4328899637227, 846.504794695101, 885.1325700813206, 888.8577827547593, 889.168627333442, 889.6574818694817, 890.1676932068505, 881.8280435315116, 887.6183241206279, 888.586807134679, 888.9155530224618, 890.995545022274, 646.4634353486642, 646.9967650161719, 647.893097638889, 650.1028580558799, 653.539254825347, 14465.349059292252, 14749.115053096848, 15841.683166336725, 16770.916145419316, 16845.241964111643, 14514.419423223058, 15291.158986255752, 15461.226938653135, 15785.447509337233, 16013.279402426919, 11887.821682674767, 11889.346085965268, 12001.33992630405, 12188.634340969786, 12307.015438764967, 3171.4767063434506, 3194.520821876737, 3198.584184056081, 3215.533747606584, 3225.7903236289567, 67548.6622566884, 74573.99999999946, 75995.62002190009, 76502.6174869122, 76944.61527692381, 12052.216605920587, 12287.877121228852, 12383.0, 12709.364531773374, 12737.808932866015, 24615.3846153844, 24996.250112496655, 25072.0, 25076.623850642256, 25149.24552263435, 7923.643436045391, 8014.398920081017, 8071.23323284287, 8293.626786794608, 8581.528015959057, 4158.7296825706235, 4195.223883581547, 4217.36739489073, 4224.5353011168745, 4337.566243375637, 4286.142771445723, 4323.524412314643, 4334.089841133397, 4350.58669426404, 4381.255186618282, 732.9853402931911, 831.8710599857031, 842.6208206307186, 852.7271273192642, 876.3120950054176, 632.7532262417661, 645.8740545593564, 662.2417332154656, 666.73997141115, 677.701811203069, 10813.18901082422, 11157.163212759075, 11348.659540213806, 11381.886181138083, 11390.544378224778, 626.9153664255358, 643.3759253524074, 646.1825790375179, 646.2406672160193, 647.4852492268658, 4870.659053866196, 5091.872703182376, 5113.194557944429, 5122.692638441706, 5152.948470515322, 80864.59567702241, 81607.0, 82044.58977705192, 82682.58658706667, 85855.57072214599, 53624.731876341146, 66221.66889165524, 66631.0, 68735.31264687289, 69161.65419172872, 61349.38650613526, 61661.38338616646, 61949.380506194364, 63475.682621586595, 64467.0, 10249.54060823954, 10271.0, 10485.580576776843, 10528.631497897537, 10532.104771094237, 34387.0, 34867.30265394627, 35233.647663523036, 35325.0, 36200.27599448049, 41723.0, 41970.0, 42156.78921605495, 42169.78915105405, 42737.5726242746, 13004.934975325063, 13053.216806991612, 13127.463078698762, 13169.209847409185, 13255.138416002928, 13364.866351336363, 13376.398062087424, 13442.865571344162, 14140.50508232207, 14337.211453369748, 12788.744225115632, 13324.800127998282, 13396.531121410704, 13662.0, 13840.792388113985, 13344.501662858624, 13496.5276215332, 13808.585742427744, 13897.583072507841, 13960.441582336593, 12902.0, 13039.934800325938, 13184.472621095048, 13361.0, 13613.183209007491, 9380.390274632127, 9704.466254356079, 10025.398476091457, 10026.548805303924, 10245.0, 10712.785744285227, 10747.785044299226, 10850.186236032172, 10858.511366988347, 10872.130229581773], "value": 7276.341007065925, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 110.0, 111.0, 111.0, 113.0, 111.0, 113.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 120.0, 169.0, 121.0, 121.0, 119.0, 119.0, 146.0, 122.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 165.0, 164.0, 165.0, 164.0, 167.0, 164.0, 165.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 108.0, 76.0, 75.0, 75.0, 77.0, 99.0, 74.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 106.0, 84.0, 83.0, 84.0, 101.0, 84.0, 84.0, 105.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 57.0, 57.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0], "value": 58.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 56.0, 58.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 82.0, 80.0, 80.0, 81.0, 81.0, 81.0, 80.0, 81.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 132.0, 141.0, 146.0, 144.0, 127.0, 136.0, 129.0, 176.0, 129.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 190.0, 185.0, 184.0, 187.0, 185.0, 183.0, 190.0, 186.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 71.0, 65.0, 66.0, 75.0, 65.0, 68.0, 64.0], "value": 67.0, "unit": "score"}]}]} 18:15:58 INFO - Return code: 0 18:15:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:15:58 ERROR - # TBPL SUCCESS # 18:15:58 INFO - Running post-action listener: _resource_record_post_action 18:15:58 INFO - [mozharness: 2017-03-28 01:15:58.936496Z] Finished run-tests step (success) 18:15:58 INFO - Running post-run listener: _resource_record_post_run 18:15:59 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 380473344; Read time: 4; Write time: 469052 18:15:59 INFO - TinderboxPrint: CPU usage
13.0% 18:15:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 18:15:59 INFO - TinderboxPrint: I/O write bytes / time
380,473,344 / 469,052 18:15:59 INFO - TinderboxPrint: CPU idle
8,298.7 (87.0%) 18:15:59 INFO - TinderboxPrint: CPU user
1,189.4 (12.5%) 18:15:59 INFO - TinderboxPrint: Swap in / out
0 / 0 18:15:59 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 121659392; Read time: 0; Write time: 167508 18:15:59 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 258027520; Read time: 0; Write time: 301516 18:15:59 INFO - Running post-run listener: _upload_blobber_files 18:15:59 INFO - Blob upload gear active. 18:15:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:15:59 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 18:15:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:15:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:15:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:15:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:15:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:15:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:16:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:00 INFO - (blobuploader) - INFO - Done attempting. 18:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:16:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:03 INFO - (blobuploader) - INFO - Done attempting. 18:16:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:16:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:16:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:04 INFO - (blobuploader) - INFO - Done attempting. 18:16:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:16:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:16:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:05 INFO - (blobuploader) - INFO - Done attempting. 18:16:05 INFO - (blobuploader) - INFO - Iteration through files over. 18:16:05 INFO - Return code: 0 18:16:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:16:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:16:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24f5bf4b95499beb90c487a9ae16e50cd8f7822816907216877a54be7b338306eb055d0aa90d5bb0c881ccff1298e9ec3ee64c9f57ee18f9d272f4646f72607a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04189c3d62c42229d5446623620c3a9454f8b9800470de64f2cbe33667ed2edf3c0090955eecfd0aa3367996628c94fc936c2f47d49471a81e1dfddbd2f374cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707037dc5e8503d274510e9255062ab3c08b66adbad0d8709dea0a9f75ded5c6315ffc65ee9b52b29f7eb0e31c1b43deb76f05573b76e597e29a5b0d6927bf09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20550bfc4202624a8389407ade4c29f06e0a0ec29614da8e9093621cfb47d934144bc3fcfe67c69dbfd70aa9cd3a052888f39485944c1a61b759c47e921fe5c5"} 18:16:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:16:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:16:05 INFO - Contents: 18:16:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24f5bf4b95499beb90c487a9ae16e50cd8f7822816907216877a54be7b338306eb055d0aa90d5bb0c881ccff1298e9ec3ee64c9f57ee18f9d272f4646f72607a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04189c3d62c42229d5446623620c3a9454f8b9800470de64f2cbe33667ed2edf3c0090955eecfd0aa3367996628c94fc936c2f47d49471a81e1dfddbd2f374cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707037dc5e8503d274510e9255062ab3c08b66adbad0d8709dea0a9f75ded5c6315ffc65ee9b52b29f7eb0e31c1b43deb76f05573b76e597e29a5b0d6927bf09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20550bfc4202624a8389407ade4c29f06e0a0ec29614da8e9093621cfb47d934144bc3fcfe67c69dbfd70aa9cd3a052888f39485944c1a61b759c47e921fe5c5"} 18:16:05 INFO - Running post-run listener: copy_logs_to_upload_dir 18:16:05 INFO - Copying logs to upload dir... 18:16:05 INFO - mkdir: /builds/slave/test/build/upload/logs 18:16:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.031423 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-27 18:16:05.110124) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:16:05.113985) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24f5bf4b95499beb90c487a9ae16e50cd8f7822816907216877a54be7b338306eb055d0aa90d5bb0c881ccff1298e9ec3ee64c9f57ee18f9d272f4646f72607a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04189c3d62c42229d5446623620c3a9454f8b9800470de64f2cbe33667ed2edf3c0090955eecfd0aa3367996628c94fc936c2f47d49471a81e1dfddbd2f374cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707037dc5e8503d274510e9255062ab3c08b66adbad0d8709dea0a9f75ded5c6315ffc65ee9b52b29f7eb0e31c1b43deb76f05573b76e597e29a5b0d6927bf09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20550bfc4202624a8389407ade4c29f06e0a0ec29614da8e9093621cfb47d934144bc3fcfe67c69dbfd70aa9cd3a052888f39485944c1a61b759c47e921fe5c5"} build_url:https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/Zi5T8S9yTPmZRgtHSYAJHA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24f5bf4b95499beb90c487a9ae16e50cd8f7822816907216877a54be7b338306eb055d0aa90d5bb0c881ccff1298e9ec3ee64c9f57ee18f9d272f4646f72607a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04189c3d62c42229d5446623620c3a9454f8b9800470de64f2cbe33667ed2edf3c0090955eecfd0aa3367996628c94fc936c2f47d49471a81e1dfddbd2f374cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/707037dc5e8503d274510e9255062ab3c08b66adbad0d8709dea0a9f75ded5c6315ffc65ee9b52b29f7eb0e31c1b43deb76f05573b76e597e29a5b0d6927bf09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20550bfc4202624a8389407ade4c29f06e0a0ec29614da8e9093621cfb47d934144bc3fcfe67c69dbfd70aa9cd3a052888f39485944c1a61b759c47e921fe5c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:16:05.143990) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:16:05.144351) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490662509.934438-488505103 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:16:05.228334) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:16:05.228706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:16:05.228996) ========= ========= Total master_lag: 0.16 =========