builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1490703010.23 results: success (0) revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.232098) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.232514) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.232795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.247194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.247453) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-28 05:10: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% 46.7M=0s 2017-03-28 05:10:10 (46.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128166 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.389696) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.390004) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013522 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:10.439884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 05:10:10.440226) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 --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 d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 --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=d6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-28 05:10:10,852 truncating revision to first 12 chars 2017-03-28 05:10:10,852 Setting DEBUG logging. 2017-03-28 05:10:10,852 attempt 1/10 2017-03-28 05:10:10,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d4af7ec6cfcd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 05:10:12,093 unpacking tar archive at: mozilla-central-d4af7ec6cfcd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.558253 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 05:10:12.011484) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:12.011802) ========= script_repo_revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:12.012180) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:12.012442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 05:10:12.024713) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-28 05:10:12.024979) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:10:12 INFO - MultiFileLogger online at 20170328 05:10:12 in /builds/slave/test 05:10:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:10:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:12 INFO - {'append_to_log': False, 05:10:12 INFO - 'base_work_dir': '/builds/slave/test', 05:10:12 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:10:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:12 INFO - 'branch': 'Firefox-Non-PGO', 05:10:12 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:12 INFO - 'config_files': ('talos/linux_config.py',), 05:10:12 INFO - 'default_actions': ('clobber', 05:10:12 INFO - 'read-buildbot-config', 05:10:12 INFO - 'download-and-extract', 05:10:12 INFO - 'populate-webroot', 05:10:12 INFO - 'create-virtualenv', 05:10:12 INFO - 'install', 05:10:12 INFO - 'run-tests'), 05:10:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:12 INFO - 'download_minidump_stackwalk': True, 05:10:12 INFO - 'download_symbols': 'ondemand', 05:10:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:12 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:12 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:12 INFO - 'gecko_profile': False, 05:10:12 INFO - 'gecko_profile_interval': 0, 05:10:12 INFO - 'installer_path': 'installer.exe', 05:10:12 INFO - 'log_level': 'info', 05:10:12 INFO - 'log_name': 'talos', 05:10:12 INFO - 'log_to_console': True, 05:10:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:10:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:10:12 INFO - 'opt_config_files': (), 05:10:12 INFO - 'pip_index': False, 05:10:12 INFO - 'suite': 'dromaeojs-e10s', 05:10:12 INFO - 'system_bits': '32', 05:10:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:12 INFO - 'title': 'talos-linux64-ix-034', 05:10:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:12 INFO - 'use_talos_json': True, 05:10:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:12 INFO - 'work_dir': 'build'} 05:10:12 INFO - [mozharness: 2017-03-28 12:10:12.536663Z] Running clobber step. 05:10:12 INFO - Running pre-action listener: _resource_record_pre_action 05:10:12 INFO - Running main action method: clobber 05:10:12 INFO - rmtree: /builds/slave/test/build 05:10:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:13 INFO - Running post-action listener: _resource_record_post_action 05:10:13 INFO - [mozharness: 2017-03-28 12:10:13.467355Z] Finished clobber step (success) 05:10:13 INFO - [mozharness: 2017-03-28 12:10:13.467453Z] Running read-buildbot-config step. 05:10:13 INFO - Running pre-action listener: _resource_record_pre_action 05:10:13 INFO - Running main action method: read_buildbot_config 05:10:13 INFO - Using buildbot properties: 05:10:13 INFO - { 05:10:13 INFO - "project": "", 05:10:13 INFO - "product": "firefox", 05:10:13 INFO - "who": "cbook@mozilla.com", 05:10:13 INFO - "installer_path": "public/build/target.tar.bz2", 05:10:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:10:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:10:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:10:13 INFO - "stage_platform": "linux64", 05:10:13 INFO - "basedir": "/builds/slave/test", 05:10:13 INFO - "buildnumber": 774, 05:10:13 INFO - "platform": "ubuntu64_hw", 05:10:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:10:13 INFO - "slavebuilddir": "test", 05:10:13 INFO - "taskId": "bm4CXFyYT9eRKDWG7BpQKQ", 05:10:13 INFO - "branch": "mozilla-central", 05:10:13 INFO - "script_repo_revision": "production", 05:10:13 INFO - "revision": "d4af7ec6cfcd9b81cd1f433a00b412de61e95b62", 05:10:13 INFO - "slavename": "talos-linux64-ix-034", 05:10:13 INFO - "repo_path": "mozilla-central" 05:10:13 INFO - } 05:10:13 INFO - Finding installer, test and symbols from parent task. 05:10:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bm4CXFyYT9eRKDWG7BpQKQ'}, attempt #1 05:10:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bm4CXFyYT9eRKDWG7BpQKQ'}, attempt #1 05:10:14 INFO - Task dependencies: bsyIprExTAeKjTbpenp6iA 05:10:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA'}, attempt #1 05:10:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 05:10:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/test_packages.json 05:10:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip 05:10:14 INFO - Running post-action listener: _resource_record_post_action 05:10:14 INFO - [mozharness: 2017-03-28 12:10:14.861715Z] Finished read-buildbot-config step (success) 05:10:14 INFO - [mozharness: 2017-03-28 12:10:14.861933Z] Running download-and-extract step. 05:10:14 INFO - Running pre-action listener: _resource_record_pre_action 05:10:14 INFO - Running main action method: download_and_extract 05:10:14 INFO - mkdir: /builds/slave/test/build/tests 05:10:14 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')]} 05:10:14 INFO - trying https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.test_packages.json 05:10:14 INFO - Downloading https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:10:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:10:15 INFO - Downloaded 986 bytes. 05:10:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:10:15 INFO - Using the following test package requirements: 05:10:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:10:15 INFO - u'common': [u'target.common.tests.zip'], 05:10:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:10:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:10:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:10:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:10:15 INFO - u'mozbase': [u'target.common.tests.zip'], 05:10:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:10:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:10:15 INFO - u'web-platform': [u'target.common.tests.zip', 05:10:15 INFO - u'target.web-platform.tests.zip'], 05:10:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:10:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:10:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip 05:10:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:10:15 INFO - Fetch https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip into memory 05:10:16 INFO - Expected file size: 58220873 05:10:16 INFO - Obtained file size: 58220873 05:10:26 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:10:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip 05:10:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:10:26 INFO - Fetch https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.common.tests.zip into memory 05:10:27 INFO - Expected file size: 58220873 05:10:27 INFO - Obtained file size: 58220873 05:10:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.talos.tests.zip 05:10:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:10:38 INFO - Fetch https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.talos.tests.zip into memory 05:10:39 INFO - Expected file size: 14404171 05:10:39 INFO - Obtained file size: 14404171 05:10: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')]} 05:10:39 INFO - trying https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 05:10:39 INFO - Downloading https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:10:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:10:40 INFO - Downloaded 62551454 bytes. 05:10:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 05:10:40 INFO - mkdir: /builds/slave/test/properties 05:10:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:10:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:10:40 INFO - Contents: 05:10:40 INFO - build_url:https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 05:10:40 INFO - Running post-action listener: _resource_record_post_action 05:10:40 INFO - Running post-action listener: set_extra_try_arguments 05:10:40 INFO - [mozharness: 2017-03-28 12:10:40.539655Z] Finished download-and-extract step (success) 05:10:40 INFO - [mozharness: 2017-03-28 12:10:40.539751Z] Running populate-webroot step. 05:10:40 INFO - Running pre-action listener: _resource_record_pre_action 05:10:40 INFO - Running main action method: populate_webroot 05:10:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:10:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:10:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:10:40 INFO - '--tppagecycles', 05:10:40 INFO - '7'], 05:10:40 INFO - 'tests': ['tsvgm']}}, 05:10:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:10:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:10:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:10:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:10:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['damp', 'tps']}, 05:10:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'tests': ['damp', 'tps']}, 05:10:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['dromaeo_dom']}, 05:10:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:10:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['basic_compositor_video']}, 05:10:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:10:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['a11yr', 05:10:40 INFO - 'ts_paint', 05:10:40 INFO - 'tpaint', 05:10:40 INFO - 'sessionrestore', 05:10:40 INFO - 'sessionrestore_no_auto_restore', 05:10:40 INFO - 'tabpaint']}, 05:10:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:10:40 INFO - 'ts_paint', 05:10:40 INFO - 'tpaint', 05:10:40 INFO - 'sessionrestore', 05:10:40 INFO - 'sessionrestore_no_auto_restore', 05:10:40 INFO - 'tabpaint']}, 05:10:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:10:40 INFO - 'ts_paint', 05:10:40 INFO - 'tpaint', 05:10:40 INFO - 'sessionrestore', 05:10:40 INFO - 'sessionrestore_no_auto_restore', 05:10:40 INFO - 'tabpaint']}, 05:10:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:10:40 INFO - 'ts_paint', 05:10:40 INFO - 'tpaint', 05:10:40 INFO - 'sessionrestore', 05:10:40 INFO - 'sessionrestore_no_auto_restore', 05:10:40 INFO - 'tabpaint']}, 05:10:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['a11yr', 05:10:40 INFO - 'ts_paint', 05:10:40 INFO - 'tpaint', 05:10:40 INFO - 'sessionrestore', 05:10:40 INFO - 'sessionrestore_no_auto_restore', 05:10:40 INFO - 'tabpaint']}, 05:10:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['a11yr', 05:10:40 INFO - 'ts_paint', 05:10:40 INFO - 'tpaint', 05:10:40 INFO - 'sessionrestore', 05:10:40 INFO - 'sessionrestore_no_auto_restore', 05:10:40 INFO - 'tabpaint']}, 05:10:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['tsvgx', 05:10:40 INFO - 'tsvgr_opacity', 05:10:40 INFO - 'tart', 05:10:40 INFO - 'tscrollx', 05:10:40 INFO - 'cart', 05:10:40 INFO - 'tsvg_static']}, 05:10:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:10:40 INFO - 'tsvgr_opacity', 05:10:40 INFO - 'tart', 05:10:40 INFO - 'tscrollx', 05:10:40 INFO - 'cart', 05:10:40 INFO - 'tsvg_static']}, 05:10:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'talos_options': ['--disable-e10s'], 05:10:40 INFO - 'tests': ['tp5o']}, 05:10:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:10:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'talos_options': ['--disable-e10s', 05:10:40 INFO - '--xperf_path', 05:10:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:40 INFO - 'tests': ['tp5n']}, 05:10:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:10:40 INFO - 'talos_options': ['--xperf_path', 05:10:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:40 INFO - 'tests': ['tp5n']}}, 05:10:40 INFO - 'talos.zip': {'path': '', 05:10:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:10:40 INFO - Running post-action listener: _resource_record_post_action 05:10:40 INFO - [mozharness: 2017-03-28 12:10:40.552972Z] Finished populate-webroot step (success) 05:10:40 INFO - [mozharness: 2017-03-28 12:10:40.553062Z] Running create-virtualenv step. 05:10:40 INFO - Running pre-action listener: _resource_record_pre_action 05:10:40 INFO - Running main action method: create_virtualenv 05:10:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:10:40 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 05:10:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:10:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:10:40 INFO - Using real prefix '/usr' 05:10:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:10:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:10:43 INFO - Installing pip.................done. 05:10:43 INFO - Return code: 0 05:10:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:10:43 INFO - Reading from file tmpfile_stdout 05:10:43 INFO - Output received: 05:10: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) 05:10:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10: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 05:10: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 05:10:43 INFO - Using env: {'DISPLAY': ':0', 05:10:43 INFO - 'HOME': '/home/cltbld', 05:10:43 INFO - 'LANG': 'en_US.UTF-8', 05:10:43 INFO - 'LANGUAGE': 'en_US:en', 05:10:43 INFO - 'LOGNAME': 'cltbld', 05:10:43 INFO - 'MAIL': '/var/mail/cltbld', 05:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:43 INFO - 'MOZ_NO_REMOTE': '1', 05:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:43 INFO - 'NO_EM_RESTART': '1', 05:10:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:43 INFO - 'PWD': '/builds/slave/test', 05:10:43 INFO - 'SHELL': '/bin/bash', 05:10:43 INFO - 'SHLVL': '1', 05:10:43 INFO - 'TERM': 'linux', 05:10:43 INFO - 'TMOUT': '86400', 05:10:43 INFO - 'USER': 'cltbld', 05:10:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:43 INFO - '_': '/tools/buildbot/bin/python'} 05:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:43 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 05:10:43 INFO - Cleaning up... 05:10:43 INFO - Return code: 0 05:10:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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', '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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:10: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 psutil>=3.1.1 05:10:43 INFO - Using env: {'DISPLAY': ':0', 05:10:43 INFO - 'HOME': '/home/cltbld', 05:10:43 INFO - 'LANG': 'en_US.UTF-8', 05:10:43 INFO - 'LANGUAGE': 'en_US:en', 05:10:43 INFO - 'LOGNAME': 'cltbld', 05:10:43 INFO - 'MAIL': '/var/mail/cltbld', 05:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:43 INFO - 'MOZ_NO_REMOTE': '1', 05:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:43 INFO - 'NO_EM_RESTART': '1', 05:10:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:43 INFO - 'PWD': '/builds/slave/test', 05:10:43 INFO - 'SHELL': '/bin/bash', 05:10:43 INFO - 'SHLVL': '1', 05:10:43 INFO - 'TERM': 'linux', 05:10:43 INFO - 'TMOUT': '86400', 05:10:43 INFO - 'USER': 'cltbld', 05:10:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:43 INFO - '_': '/tools/buildbot/bin/python'} 05:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:43 INFO - Downloading/unpacking psutil>=3.1.1 05:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:10:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:45 INFO - Installing collected packages: psutil 05:10:45 INFO - Running setup.py install for psutil 05:10:45 INFO - building 'psutil._psutil_linux' extension 05:10:45 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 05:10: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 05:10:46 INFO - building 'psutil._psutil_posix' extension 05:10: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 05:10: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 05:10:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:46 INFO - Successfully installed psutil 05:10:46 INFO - Cleaning up... 05:10:46 INFO - Return code: 0 05:10:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:10:46 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')]} 05:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:46 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')]} 05:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:46 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10:46 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 05:10:46 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 05:10:46 INFO - Using env: {'DISPLAY': ':0', 05:10:46 INFO - 'HOME': '/home/cltbld', 05:10:46 INFO - 'LANG': 'en_US.UTF-8', 05:10:46 INFO - 'LANGUAGE': 'en_US:en', 05:10:46 INFO - 'LOGNAME': 'cltbld', 05:10:46 INFO - 'MAIL': '/var/mail/cltbld', 05:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:46 INFO - 'MOZ_NO_REMOTE': '1', 05:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:46 INFO - 'NO_EM_RESTART': '1', 05:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:46 INFO - 'PWD': '/builds/slave/test', 05:10:46 INFO - 'SHELL': '/bin/bash', 05:10:46 INFO - 'SHLVL': '1', 05:10:46 INFO - 'TERM': 'linux', 05:10:46 INFO - 'TMOUT': '86400', 05:10:46 INFO - 'USER': 'cltbld', 05:10:46 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:46 INFO - '_': '/tools/buildbot/bin/python'} 05:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:10:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:10:48 INFO - Installing collected packages: mozsystemmonitor 05:10:48 INFO - Running setup.py install for mozsystemmonitor 05:10:48 INFO - Successfully installed mozsystemmonitor 05:10:48 INFO - Cleaning up... 05:10:48 INFO - Return code: 0 05:10:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:48 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10:48 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 05:10:48 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 05:10:48 INFO - Using env: {'DISPLAY': ':0', 05:10:48 INFO - 'HOME': '/home/cltbld', 05:10:48 INFO - 'LANG': 'en_US.UTF-8', 05:10:48 INFO - 'LANGUAGE': 'en_US:en', 05:10:48 INFO - 'LOGNAME': 'cltbld', 05:10:48 INFO - 'MAIL': '/var/mail/cltbld', 05:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:48 INFO - 'MOZ_NO_REMOTE': '1', 05:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:48 INFO - 'NO_EM_RESTART': '1', 05:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:48 INFO - 'PWD': '/builds/slave/test', 05:10:48 INFO - 'SHELL': '/bin/bash', 05:10:48 INFO - 'SHLVL': '1', 05:10:48 INFO - 'TERM': 'linux', 05:10:48 INFO - 'TMOUT': '86400', 05:10:48 INFO - 'USER': 'cltbld', 05:10:48 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:48 INFO - '_': '/tools/buildbot/bin/python'} 05:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:49 INFO - Downloading/unpacking jsonschema==2.5.1 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:10:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:10:51 INFO - warning: no files found matching '*.txt' 05:10:51 INFO - no previously-included directories found matching 'build' 05:10:51 INFO - no previously-included directories found matching 'dist' 05:10:51 INFO - no previously-included directories found matching '.git*' 05:10:51 INFO - Installing collected packages: jsonschema, functools32 05:10:51 INFO - Running setup.py install for functools32 05:10:51 INFO - warning: no files found matching '*.txt' 05:10:51 INFO - no previously-included directories found matching 'build' 05:10:51 INFO - no previously-included directories found matching 'dist' 05:10:51 INFO - no previously-included directories found matching '.git*' 05:10:51 INFO - Successfully installed jsonschema functools32 05:10:51 INFO - Cleaning up... 05:10:51 INFO - Return code: 0 05:10:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:10:51 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')]} 05:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:51 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')]} 05:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:51 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10:51 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 05:10:51 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 05:10:51 INFO - Using env: {'DISPLAY': ':0', 05:10:51 INFO - 'HOME': '/home/cltbld', 05:10:51 INFO - 'LANG': 'en_US.UTF-8', 05:10:51 INFO - 'LANGUAGE': 'en_US:en', 05:10:51 INFO - 'LOGNAME': 'cltbld', 05:10:51 INFO - 'MAIL': '/var/mail/cltbld', 05:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:51 INFO - 'MOZ_NO_REMOTE': '1', 05:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:51 INFO - 'NO_EM_RESTART': '1', 05:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:51 INFO - 'PWD': '/builds/slave/test', 05:10:51 INFO - 'SHELL': '/bin/bash', 05:10:51 INFO - 'SHLVL': '1', 05:10:51 INFO - 'TERM': 'linux', 05:10:51 INFO - 'TMOUT': '86400', 05:10:51 INFO - 'USER': 'cltbld', 05:10:51 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:51 INFO - '_': '/tools/buildbot/bin/python'} 05:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:10:51 INFO - Cleaning up... 05:10:51 INFO - Return code: 0 05:10:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:10:51 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')]} 05:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:51 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')]} 05:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:51 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10:51 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 05:10:51 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 05:10:51 INFO - Using env: {'DISPLAY': ':0', 05:10:51 INFO - 'HOME': '/home/cltbld', 05:10:51 INFO - 'LANG': 'en_US.UTF-8', 05:10:51 INFO - 'LANGUAGE': 'en_US:en', 05:10:51 INFO - 'LOGNAME': 'cltbld', 05:10:51 INFO - 'MAIL': '/var/mail/cltbld', 05:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:51 INFO - 'MOZ_NO_REMOTE': '1', 05:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:51 INFO - 'NO_EM_RESTART': '1', 05:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:51 INFO - 'PWD': '/builds/slave/test', 05:10:51 INFO - 'SHELL': '/bin/bash', 05:10:51 INFO - 'SHLVL': '1', 05:10:51 INFO - 'TERM': 'linux', 05:10:51 INFO - 'TMOUT': '86400', 05:10:51 INFO - 'USER': 'cltbld', 05:10:51 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:51 INFO - '_': '/tools/buildbot/bin/python'} 05:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:10:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:10:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:54 INFO - Downloading docopt-0.6.1.tar.gz 05:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:10:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:10:54 INFO - Running setup.py install for blobuploader 05:10:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:10:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:10:54 INFO - Running setup.py install for requests 05:10:55 INFO - Running setup.py install for docopt 05:10:55 INFO - Successfully installed blobuploader requests docopt 05:10:55 INFO - Cleaning up... 05:10:55 INFO - Return code: 0 05:10:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:55 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10:55 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 05:10:55 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 05:10:55 INFO - Using env: {'DISPLAY': ':0', 05:10:55 INFO - 'HOME': '/home/cltbld', 05:10:55 INFO - 'LANG': 'en_US.UTF-8', 05:10:55 INFO - 'LANGUAGE': 'en_US:en', 05:10:55 INFO - 'LOGNAME': 'cltbld', 05:10:55 INFO - 'MAIL': '/var/mail/cltbld', 05:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:55 INFO - 'MOZ_NO_REMOTE': '1', 05:10:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:55 INFO - 'NO_EM_RESTART': '1', 05:10:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:55 INFO - 'PWD': '/builds/slave/test', 05:10:55 INFO - 'SHELL': '/bin/bash', 05:10:55 INFO - 'SHLVL': '1', 05:10:55 INFO - 'TERM': 'linux', 05:10:55 INFO - 'TMOUT': '86400', 05:10:55 INFO - 'USER': 'cltbld', 05:10:55 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:55 INFO - '_': '/tools/buildbot/bin/python'} 05:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:55 INFO - Running setup.py (path:/tmp/pip-tEbEOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:55 INFO - Running setup.py (path:/tmp/pip-RWgyjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:55 INFO - Running setup.py (path:/tmp/pip-Nxz_3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:55 INFO - Running setup.py (path:/tmp/pip-e42QOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:55 INFO - Running setup.py (path:/tmp/pip-pmhJNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:56 INFO - Running setup.py (path:/tmp/pip-KCGugu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:56 INFO - Running setup.py (path:/tmp/pip-3IVlTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:56 INFO - Running setup.py (path:/tmp/pip-7GyPEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:56 INFO - Running setup.py (path:/tmp/pip-64qtvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:56 INFO - Running setup.py (path:/tmp/pip-ycIUJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:56 INFO - Running setup.py (path:/tmp/pip-IOs10h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:56 INFO - Running setup.py (path:/tmp/pip-VX9kmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:56 INFO - Running setup.py (path:/tmp/pip-4cXJxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:56 INFO - Running setup.py (path:/tmp/pip-On9A_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:56 INFO - Running setup.py (path:/tmp/pip-3ynbHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:56 INFO - Running setup.py (path:/tmp/pip-pq5PqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:56 INFO - Running setup.py (path:/tmp/pip-DT0ahd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:10:56 INFO - Running setup.py install for manifestparser 05:10:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:10:56 INFO - Running setup.py install for mozcrash 05:10:57 INFO - Running setup.py install for mozdebug 05:10:57 INFO - Running setup.py install for mozdevice 05:10:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Running setup.py install for mozfile 05:10:57 INFO - Running setup.py install for mozhttpd 05:10:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Running setup.py install for mozinfo 05:10:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Running setup.py install for mozInstall 05:10:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Running setup.py install for mozleak 05:10:57 INFO - Running setup.py install for mozlog 05:10:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Running setup.py install for moznetwork 05:10:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:57 INFO - Running setup.py install for mozprocess 05:10:58 INFO - Running setup.py install for mozprofile 05:10:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:58 INFO - Running setup.py install for mozrunner 05:10:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:58 INFO - Running setup.py install for mozscreenshot 05:10:58 INFO - Running setup.py install for moztest 05:10:58 INFO - Running setup.py install for mozversion 05:10:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:58 INFO - Cleaning up... 05:10:58 INFO - Return code: 0 05:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:58 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:10:58 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 05:10:58 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 05:10:58 INFO - Using env: {'DISPLAY': ':0', 05:10:58 INFO - 'HOME': '/home/cltbld', 05:10:58 INFO - 'LANG': 'en_US.UTF-8', 05:10:58 INFO - 'LANGUAGE': 'en_US:en', 05:10:58 INFO - 'LOGNAME': 'cltbld', 05:10:58 INFO - 'MAIL': '/var/mail/cltbld', 05:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:58 INFO - 'MOZ_NO_REMOTE': '1', 05:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:58 INFO - 'NO_EM_RESTART': '1', 05:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:58 INFO - 'PWD': '/builds/slave/test', 05:10:58 INFO - 'SHELL': '/bin/bash', 05:10:58 INFO - 'SHLVL': '1', 05:10:58 INFO - 'TERM': 'linux', 05:10:58 INFO - 'TMOUT': '86400', 05:10:58 INFO - 'USER': 'cltbld', 05:10:58 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:58 INFO - '_': '/tools/buildbot/bin/python'} 05:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:58 INFO - Running setup.py (path:/tmp/pip-GjligG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:58 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)) 05:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:58 INFO - Running setup.py (path:/tmp/pip-LarSK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10: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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:59 INFO - Running setup.py (path:/tmp/pip-jTNLmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10: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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:59 INFO - Running setup.py (path:/tmp/pip-Er9tqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10: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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:59 INFO - Running setup.py (path:/tmp/pip-6qpiwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10: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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:59 INFO - Running setup.py (path:/tmp/pip-fsR9iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10: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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:59 INFO - Running setup.py (path:/tmp/pip-dEGK2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:59 INFO - Running setup.py (path:/tmp/pip-0tUucg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:59 INFO - Running setup.py (path:/tmp/pip-2Alkiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:59 INFO - Running setup.py (path:/tmp/pip-Xqz_0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:59 INFO - Running setup.py (path:/tmp/pip-_YeH1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:59 INFO - Running setup.py (path:/tmp/pip-Y2NqW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:59 INFO - Running setup.py (path:/tmp/pip-xVRGzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:59 INFO - Running setup.py (path:/tmp/pip-RYx0oH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:59 INFO - Running setup.py (path:/tmp/pip-d2CU3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:59 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)) 05:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:59 INFO - Running setup.py (path:/tmp/pip-c3m3LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11: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)) 05:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:00 INFO - Running setup.py (path:/tmp/pip-rfTzw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11: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)) 05:11: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)) 05:11: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)) 05:11: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)) 05:11: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)) 05:11: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)) 05:11:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:02 INFO - Downloading blessings-1.6.tar.gz 05:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:02 INFO - Installing collected packages: blessings 05:11:02 INFO - Running setup.py install for blessings 05:11:02 INFO - Successfully installed blessings 05:11:02 INFO - Cleaning up... 05:11:02 INFO - Return code: 0 05:11:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:02 INFO - Reading from file tmpfile_stdout 05:11:02 INFO - Current package versions: 05:11:02 INFO - argparse == 1.2.1 05:11:02 INFO - blessings == 1.6 05:11:02 INFO - blobuploader == 1.2.4 05:11:02 INFO - docopt == 0.6.1 05:11:02 INFO - functools32 == 3.2.3-2 05:11:02 INFO - jsonschema == 2.5.1 05:11:02 INFO - manifestparser == 1.1 05:11:02 INFO - mozInstall == 1.12 05:11:02 INFO - mozcrash == 1.0 05:11:02 INFO - mozdebug == 0.1 05:11:02 INFO - mozdevice == 0.49 05:11:02 INFO - mozfile == 1.2 05:11:02 INFO - mozhttpd == 0.7 05:11:02 INFO - mozinfo == 0.9 05:11:02 INFO - mozleak == 0.1 05:11:02 INFO - mozlog == 3.4 05:11:02 INFO - moznetwork == 0.27 05:11:02 INFO - mozprocess == 0.25 05:11:02 INFO - mozprofile == 0.28 05:11:02 INFO - mozrunner == 6.13 05:11:02 INFO - mozscreenshot == 0.1 05:11:02 INFO - mozsystemmonitor == 0.3 05:11:02 INFO - moztest == 0.8 05:11:02 INFO - mozversion == 1.4 05:11:02 INFO - psutil == 3.1.1 05:11:02 INFO - requests == 1.2.3 05:11:02 INFO - wsgiref == 0.1.2 05:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:02 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')]} 05:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:02 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')]} 05:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:02 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:11:02 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 05:11:02 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 05:11:02 INFO - Using env: {'DISPLAY': ':0', 05:11:02 INFO - 'HOME': '/home/cltbld', 05:11:02 INFO - 'LANG': 'en_US.UTF-8', 05:11:02 INFO - 'LANGUAGE': 'en_US:en', 05:11:02 INFO - 'LOGNAME': 'cltbld', 05:11:02 INFO - 'MAIL': '/var/mail/cltbld', 05:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:02 INFO - 'MOZ_NO_REMOTE': '1', 05:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:02 INFO - 'NO_EM_RESTART': '1', 05:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:02 INFO - 'PWD': '/builds/slave/test', 05:11:02 INFO - 'SHELL': '/bin/bash', 05:11:02 INFO - 'SHLVL': '1', 05:11:02 INFO - 'TERM': 'linux', 05:11:02 INFO - 'TMOUT': '86400', 05:11:02 INFO - 'USER': 'cltbld', 05:11:02 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:02 INFO - '_': '/tools/buildbot/bin/python'} 05:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 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)) 05:11:02 INFO - Cleaning up... 05:11:02 INFO - Return code: 0 05:11:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:02 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')]} 05:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:02 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')]} 05:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:02 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 0xe141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', '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 05:11:02 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 05:11:02 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 05:11:02 INFO - Using env: {'DISPLAY': ':0', 05:11:02 INFO - 'HOME': '/home/cltbld', 05:11:02 INFO - 'LANG': 'en_US.UTF-8', 05:11:02 INFO - 'LANGUAGE': 'en_US:en', 05:11:02 INFO - 'LOGNAME': 'cltbld', 05:11:02 INFO - 'MAIL': '/var/mail/cltbld', 05:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:02 INFO - 'MOZ_NO_REMOTE': '1', 05:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:02 INFO - 'NO_EM_RESTART': '1', 05:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:02 INFO - 'PWD': '/builds/slave/test', 05:11:02 INFO - 'SHELL': '/bin/bash', 05:11:02 INFO - 'SHLVL': '1', 05:11:02 INFO - 'TERM': 'linux', 05:11:02 INFO - 'TMOUT': '86400', 05:11:02 INFO - 'USER': 'cltbld', 05:11:02 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:02 INFO - '_': '/tools/buildbot/bin/python'} 05:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:11:03 INFO - Cleaning up... 05:11:03 INFO - Return code: 0 05:11:03 INFO - Running post-action listener: _resource_record_post_action 05:11:03 INFO - Running post-action listener: _start_resource_monitoring 05:11:03 INFO - Starting resource monitoring. 05:11:03 INFO - [mozharness: 2017-03-28 12:11:03.066939Z] Finished create-virtualenv step (success) 05:11:03 INFO - [mozharness: 2017-03-28 12:11:03.067463Z] Running install step. 05:11:03 INFO - Running pre-action listener: _resource_record_pre_action 05:11:03 INFO - Running main action method: install 05:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:03 INFO - Reading from file tmpfile_stdout 05:11:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:03 INFO - Reading from file tmpfile_stdout 05:11:03 INFO - Output received: 05:11:03 INFO - Usage: mozinstall [options] installer 05:11:03 INFO - Options: 05:11:03 INFO - -h, --help show this help message and exit 05:11:03 INFO - -d DEST, --destination=DEST 05:11:03 INFO - Directory to install application into. [default: 05:11:03 INFO - "/builds/slave/test"] 05:11:03 INFO - --app=APP Application being installed. [default: firefox] 05:11:03 INFO - mkdir: /builds/slave/test/build/application 05:11:03 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'] 05:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:11:19 INFO - Reading from file tmpfile_stdout 05:11:19 INFO - Output received: 05:11:19 INFO - /builds/slave/test/build/application/firefox/firefox 05:11:19 INFO - Running post-action listener: _resource_record_post_action 05:11:19 INFO - [mozharness: 2017-03-28 12:11:19.018259Z] Finished install step (success) 05:11:19 INFO - [mozharness: 2017-03-28 12:11:19.018529Z] Running run-tests step. 05:11:19 INFO - Running pre-action listener: _resource_record_pre_action 05:11:19 INFO - Running main action method: run_tests 05:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:19 INFO - Python 2.7.3 05:11:19 INFO - Return code: 0 05:11:19 INFO - grabbing minidump binary from tooltool 05:11:19 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')]} 05:11:19 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 0x10af290>, '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 0x10afb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:19 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 05:11:19 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 05:11:19 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 05:11:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:19 INFO - Return code: 0 05:11:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:11:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:19 INFO - ENV: RUST_BACKTRACE is now 1 05:11:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:11:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:11:19 INFO - Using env: {'DISPLAY': ':0', 05:11:19 INFO - 'HOME': '/home/cltbld', 05:11:19 INFO - 'LANG': 'en_US.UTF-8', 05:11:19 INFO - 'LANGUAGE': 'en_US:en', 05:11:19 INFO - 'LOGNAME': 'cltbld', 05:11:19 INFO - 'MAIL': '/var/mail/cltbld', 05:11:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:19 INFO - 'MOZ_NO_REMOTE': '1', 05:11:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:19 INFO - 'NO_EM_RESTART': '1', 05:11:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:19 INFO - 'PWD': '/builds/slave/test', 05:11:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:19 INFO - 'RUST_BACKTRACE': '1', 05:11:19 INFO - 'SHELL': '/bin/bash', 05:11:19 INFO - 'SHLVL': '1', 05:11:19 INFO - 'TERM': 'linux', 05:11:19 INFO - 'TMOUT': '86400', 05:11:19 INFO - 'USER': 'cltbld', 05:11:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631', 05:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:19 INFO - '_': '/tools/buildbot/bin/python'} 05:11:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:11:19 INFO - mozversion application_buildid: 20170328110348 05:11:19 INFO - mozversion application_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 05:11:19 INFO - mozversion application_display_name: Nightly 05:11:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:19 INFO - mozversion application_name: Firefox 05:11:19 INFO - mozversion application_remotingname: firefox 05:11:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:11:19 INFO - mozversion application_vendor: Mozilla 05:11:19 INFO - mozversion application_version: 55.0a1 05:11:19 INFO - mozversion platform_buildid: 20170328110348 05:11:19 INFO - mozversion platform_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 05:11:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:11:19 INFO - mozversion platform_version: 55.0a1 05:11:19 INFO - using testdate: 1490703079 05:11:19 INFO - actual date: 1490703079 05:11:19 INFO - starting webserver on 'localhost:37310' 05:11:19 INFO - SUITE-START | Running 2 tests 05:11:19 INFO - TEST-START | dromaeo_css 05:11:19 INFO - operating with platform_type : linux_ 05:11:19 INFO - Initialising browser for dromaeo_css test... 05:11:19 INFO - TEST-INFO | started process 15930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeFYSTN/profile http://localhost:37310/getInfo.html) 05:11:23 INFO - PID 15930 | __metrics Screen width/height:1600/1200 05:11:23 INFO - PID 15930 | colorDepth:24 05:11:23 INFO - PID 15930 | Browser inner width/height: 1024/697 05:11:23 INFO - PID 15930 | __metrics 05:11:24 ERROR - PID 15930 | 1490703084296 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 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6376:36 < canClose@browser.js:5227:12 < forceQuit@jar:file:///tmp/tmpeFYSTN/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpeFYSTN/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpeFYSTN/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpeFYSTN/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:11:24 ERROR - PID 15930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:11:25 INFO - TEST-INFO | 15930: exit 0 05:11:25 INFO - Browser initialized. 05:11:25 INFO - Running cycle 1/1 for dromaeo_css test... 05:11:25 INFO - TEST-INFO | started process 16063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeFYSTN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:37 INFO - PID 16063 | RSS: Main: 156164096 05:11:37 INFO - PID 16063 | 05:14:44 INFO - PID 16063 | Cycle 1(1): loaded http://localhost:37310/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37310/tests/dromaeo/cssquery-ext.html) 05:14:45 INFO - PID 16063 | RSS: Main: 165449728 05:14:45 INFO - PID 16063 | 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | A coding exception was thrown and uncaught in a Task. 05:16:25 INFO - PID 16063 | 05:16:25 ERROR - PID 16063 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:16:25 INFO - PID 16063 | Full stack: 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | A coding exception was thrown and uncaught in a Task. 05:16:25 INFO - PID 16063 | 05:16:25 ERROR - PID 16063 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:16:25 INFO - PID 16063 | Full stack: 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | A coding exception was thrown and uncaught in a Task. 05:16:25 INFO - PID 16063 | 05:16:25 ERROR - PID 16063 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:16:25 INFO - PID 16063 | Full stack: 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | ************************* 05:16:25 INFO - PID 16063 | A coding exception was thrown and uncaught in a Task. 05:16:25 INFO - PID 16063 | 05:16:25 ERROR - PID 16063 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:16:25 INFO - PID 16063 | Full stack: 05:16:25 INFO - PID 16063 | ************************* 05:17:47 INFO - PID 16063 | Cycle 1(1): loaded http://localhost:37310/tests/dromaeo/cssquery-ext.html (next: http://localhost:37310/tests/dromaeo/cssquery-jquery.html) 05:17:48 INFO - PID 16063 | RSS: Main: 161812480 05:17:48 INFO - PID 16063 | 05:20:50 INFO - PID 16063 | Cycle 1(1): loaded http://localhost:37310/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37310/tests/dromaeo/cssquery-mootools.html) 05:20:51 INFO - PID 16063 | RSS: Main: 169672704 05:20:51 INFO - PID 16063 | 05:23:53 INFO - PID 16063 | Cycle 1(1): loaded http://localhost:37310/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37310/tests/dromaeo/cssquery-prototype.html) 05:23:53 INFO - PID 16063 | RSS: Main: 160829440 05:23:53 INFO - PID 16063 | 05:26:56 INFO - PID 16063 | Cycle 1(1): loaded http://localhost:37310/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37310/tests/dromaeo/cssquery-yui.html) 05:26:57 INFO - PID 16063 | RSS: Main: 168648704 05:26:57 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | Cycle 1(1): loaded http://localhost:37310/tests/dromaeo/cssquery-yui.html (next: http://localhost:37310/tests/dromaeo/cssquery-dojo.html) 05:30:00 INFO - PID 16063 | RSS: Main: 162340864 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | __start_tp_report 05:30:00 INFO - PID 16063 | _x_x_mozilla_page_load 05:30:00 INFO - PID 16063 | _x_x_mozilla_page_load_details 05:30:00 INFO - PID 16063 | |i|pagename|runs| 05:30:00 INFO - PID 16063 | |0;dojo.html;2685.6642919635046;2710.4037111835414;2720.4015116674336;2747.395572973945;2754.8347099174043;5079.390473143228;5186.585073194149;5207.27098206251;5243.79024839006;5244.973775131124;5183.999999999991;5189.143791274435;5208.296879921209;5245.86885327867;5258.737063146837;4735.9526404736025;4750.809967601292;4785.186518291881;4800.183968725308;4832.05774873898;4773.737444440554;4835.975820120912;4849.854504364875;4871.293662418965;4906.288588154715;4638.8608341749805;4663.556962088596;4700.247960326331;4769.4515130760055;4790.568848803624;4726.83456079039;4758.714477131383;4767.1419144553965;4806.711597304138;4812.5668689818085;4644.233701439259;4653.953460465385;4709.764511774398;4714.339992401066;4738.549837765407;5002.349694539686;5066.088104141253;5140.40885298188;5149.0989076911765;5204.505571970694;5528.032594296023;5545.306836645419;5547.833564993057;5562.7774889004395;5569.777208911639;5593.888122237532;5594.524465420444;5635.154726791015;5656.604037717361;5661;5034.244863270465;5202.609804264695;5226.8954620908125;5262.473752624784;5302.09864323066;5628.183913332544;5665.263515742927;5723.170140329629;5778.8266352009505;5817.505512031525;5421.430749771296;5503.367112782082;5537.252470916455;5555.30558680165;5569.832905012856;5466.6993315367245;5470.234167216593;5506.1190209566275;5537.141743029837;5562.499375056183;5304;5485.451454854483;5488.067028605146;5522.420145884705;5553.222548843165;5397.055515284809;5407.297051383295;5412.702301373383;5414.377346605112;5420.403755586883;4633.351330813689;4643.698159619614;4738.5261473853025;4781.665283430127;4790.664653474293;4064.1058967027207;4066.5933406659688;4094.590540945941;4097.672186225095;4110.0752330725345;4785.665003449725;4802.975985120052;4813.614910807197;4822.590079843252;4866.172750632401;5346.91979620306;5452.945470545244;5569.972150139304;5620.100783874642;5629.718514074362;4794.880127996758;4801.783919723621;4823.807047718122;4853.077915196172;4855.198892182821;5031.849044528597;5034.949650503448;5044.876514955542;5047.041062198171;5082.542571168613;5006.048850718352;5072.695638261716;5219.660722053054;5244.265802787537;5247.370315562004;5610;5611.074172761449;5612.803551875828;5663.207150998781;5675.31896172462;5140.177571588528;5179.715115668674;5206.973965130302;5207.505286997729;5211.687298762086;5348.331458567679;5517.613767036269;5522.06124958766;5531.363893152339;5570.3315602128005;5510.641808282448;5611.4949654529655;5663.8867222656145;5667.801626943202;5688.5733569981585;5413.377461591967;5416.133418652997;5418.187271909245;5421.349438067457;5431.23962645222;5220.034293655686;5283.498067683564;5297.337832770903;5340.305760251143;5414.35027796667;5350.839474815762;5356.651817631841;5375.623706340675;5411.621186516906;5423.457654234545;5297.708626025452;5375.81184658535;5484.862878428071;5489.725513724218;5496.4503549646315;5321.308229930084;5355.250264962986;5373.677579345252;5380.058489764354;5411.350637923317 05:30:00 INFO - PID 16063 | |1;ext.html;640.2732898160589;696.2584847137798;715.7602203261908;1162.238733629473;1175.0247294745361;32606.510902336486;33192.83403582979;33244.33511329772;33323.16692082699;33347.16632084199;33234.501482477724;33606.49590256142;33734.83132584333;33757.32485350298;33822.8308858456;6741.426978706803;6874.415674667659;7073.080499535054;7325;7330.743423980161;7176.390006849424;7202.171750248736;7246.891296630555;7256.746013889515;7257.818554536126;7206.243344448836;7220.3140701633265;7263.963680181591;7264.309890560415;7282.927170728278;7224.530405523677;7232.059832221804;7235.312645298688;7337.266273372674;7344.339009489172;7369.115706115301;7384.483086183969;7425.480216384855;7430.96284518574;7455.142658594277;34022.319553609035;34056.48915266273;34075;34111.929926306504;34484.31031379384;2593.766561009518;2661.3612732943952;2687.87904544296;2701.230149407429;2705.580635001578;20768.169273229054;21008.894955525124;21028.474288142766;21461.785382146292;21518.246861359934;2970.4207679502506;2996.535536991748;3005.8046226995175;3016.306249562591;3078.184281165493;102459;102627;128923;129044;129383;36763.448548271794;37119.4432083519;37140;37194.44208336877;37314;38775.80612096978;39025.82922512329;39182.80408597939;42816.357754633704;42924.78537607292;11897.286162830258;12027.338496382783;12043.096767742454;12076.275423474444;12083.275003499819;9548.713538593853;9548.761280967892;9549.856752148724;9555.713328600154;9557.330986830993;13009.349532523336;13023.283719395335;13053.34733263333;13070.803937940938;13191.538296159588;5517;5612.494875461228;5621.690807005572;5721.342045664802;5739.598228124075;856.8800367948493;887.1572006593761;894.2711689972651;897.5871099294267;920.0615372320196;20234.59530809405;21376.78623213779;21450.249241276793;21472.892635536722;21763.347099587038;950.4677380666849;965.9082387173207;994.5533986393552;996.8704068471052;1001.9599216031277;10545.578176872992;11304.830427543593;11318.207725459139;11366.829497557543;11392.544298228144;225953;227476;227483;228007;228129;40514;40822.79588602098;40945.79527102345;41081.38377924333;41181.1763764723;1173.3040921098607;1235.9073069519823;1250.5185503581188;1253.1603825436796;1254.830597869276;19726.506837329183;20091.497712556717;20924.686129707763;21008.159673613496;21179.68230476575;29908.252293692833;30135.246618833826;30137.246568835955;30260.394792104475;30268.394632106792;204903;205975;207077;207542;207938;27734.306642334104;28301.292467688472;28379.574306385835;28381.148565543066;28444.715552844205;32212;32826.34347312993;33348;33797.15507112243;33893;26038.08866689657;26413.735862641897;28003.57994630123;28108;29207.41585168242;27848.860755696092;27877.303067423476;28097.157085287476;28197.15408537747;28705.856470717514;30731.846340768152;31379.05862824119;31454.685453146092;31459;31489.21276968094;27271.59092613652;27524.58713119265;27610.16822056092;27629.30926726848;28873.711262887104;20723.585528289652;21247.68128478105;21603.459913502287;22596.774032259465;22632.207872724375 05:30:00 INFO - PID 16063 | |2;jquery.html;412.3608406969197;418.3097888484003;418.81153480933585;432.90043290043286;441.1683975705794;18309.711286089238;18866.056697165157;19043.333483328053;19144.13851376688;19234.51913702158;18311.35910243142;18844.34044808432;18909.43271701851;19631.31290404836;19711.211551537923;5650.463205995424;5769.1346298055205;5857.326407463132;5887.793927212549;5892.587518873681;5736.455036771521;5914.852128696774;5917.911231331534;5955.315138759033;5965.910511342333;5669.461401166903;5736.139579063153;5762.884742305151;5773.220615216955;5789.7684092636455;5649.435056494318;5710.5717071219615;5732.22614946981;5779.104238842965;5868.149118377854;5306.628536002481;5307.495787900143;5309.150535914274;5309.33633295838;5310.150375939831;21819.563608727894;22452.887735561217;22458;22480.213192538347;22955.770442295696;1531.2420351925757;1536.9385224591003;1547.8761699064048;1587.928543215558;1592.5222433269973;14212.076215046092;14376.92811535946;14383.78424323636;14698.706025879423;15153.166575838324;1613.16115619877;1615.7253266944647;1632.3960134750218;1646.1193261604983;1666.7999840019274;87139;89810.5509472455;90472.54763726139;90896.545517272;91639.54180229123;10933.234673572773;11074.944625276821;11169.385683787306;11365.261258018203;11424.714382140513;15714.607134821721;15771.526854194393;15779.132147731756;15835.762463563056;15887.364505419653;7522.962385188039;7706.845863082707;7819.491733037335;7862.680509249785;7898.60506974649;7009.754658586925;7064.964675176694;7097.858042839218;7236.638168091575;7284.781456556312;7053.576785392893;7065.431956861855;7072.681729322193;7122.537035092806;7265.636718164071;1999.8700084494462;2035.409731177942;2036.521417466894;2049.139361468187;2235.7865712944067;831.2518733140222;837.9664813407395;862.4868203418956;870.6604424274537;894.9776255593662;16686.332546697995;17271.74092388615;17668.469945901645;17821;18090.18594163266;872.5724395046509;873.6287077991828;876.5595039470054;880.7181701855345;884.429542944797;9728.978457261886;9793.265505087147;9839.803203936024;9860.901390985999;9879.802403952024;426770;426797;429091;430076;436715;75696.24303757002;87726;89251.55374223216;90968;91156.54421727982;1020.7346090016649;1039.6880935719255;1071.2180108520824;1081.7944590527775;1084.5607528950788;14942.086677688227;15106.471273505374;15317.923410382877;15324.387024519341;15454.072755634697;40929.59070409258;41358.1728365437;41697;42223.1555368897;42828.57171428246;51589.226161608356;51881;54126.903984682256;54484.72757636187;55597.44402556084;7655.157932627518;7741.496802707806;7911.802204944923;7950.681972721258;8198.59007049657;11511.481983310938;11716.472758726048;11718.296902185895;11780.469878855301;11913.702157446132;9901.653442129491;9911.553980071058;9939.204863610748;10239.129673977795;10341.689749307532;9623.326367154232;9721.173700235558;9759.463229522444;9844.507774611384;9936.602535898483;10009.5495702695;10247.692569222936;10289.228307877089;10334.431606261727;10417.374957502574;7683.154852966058;7692.730754423568;7708.8458230836195;7793.220677932161;8071.959640201761;9976.950115249378;10024.498775061364;10120.392776433437;10529.73675658084;10629.149668026384 05:30:00 INFO - PID 16063 | |3;mootools.html;1262.3751243134648;1316.387879635969;1328.966775830604;1334.9399277032533;1339.0492750147391;9434.24526037917;9731.172850307714;9764.609415623376;9776.706698799038;9954.900450995488;9642.855357169648;9898;9902.35634683745;9911.702648920525;9949.751256218598;2213.3359992002415;2261.197274967379;2261.6494443361307;2265.728112626487;2266.626006708891;2070.3788863340937;2132.1791110422523;2217.933461996143;2221.3224966385246;2230.275160572812;2157.255746767363;2180.2042254577123;2272.5000499889998;2295.6671282664006;2298.37943755186;2108.7891210878865;2130.1160018592423;2130.3289463818846;2134.6264403729365;2135.957280854382;1817.273090763705;1882.962340753191;2094.2042024030925;2146.5921474919714;2148.1622167354744;9503.667371642028;9808;9815.36200146988;9844;9856.802863942752;5209.374875014984;5404.621676482609;5428.891422171534;5439.809606663787;5442.319710036246;3263.321994053097;3329.1344250494803;3334.0664613908143;3340.5657264555452;3410.28384039352;1068.086785798147;1076.9084627806646;1087.5589843457371;1157.6411312493153;1166.7608140331213;14358.282085895768;14537.636559086002;14566;14577.344019519252;14622;4709.529047095263;4847.95152048482;4878.195097808893;4940.308356830046;4951.207806750903;12145.635630931087;12183;12193.890557907855;12215.633530994084;12275.93862030702;5594.496495315441;5620.213170156181;5622.184783206412;5649.774009039593;5650.463205995424;4871.3667223260745;5029.899402011939;5064.594832413398;5153.948460515422;5210.192420174879;3785.602511736228;3988.142549351903;3991.84032638697;4006.238814625212;4036.616521430459;1172.3376292394769;1186.1993154620598;1186.4186548591128;1192.4753108632174;1199.9880001200052;692.5221597097986;695.2387136085978;717.2218143314562;721.3579913876654;734.4895297768106;5359.722118099792;6167.599106058092;6167.84580385494;6179.474744646665;6451.516136289797;809.5790189101634;814.8573999550055;819.3854609043218;838.2078935406105;840.82342708032;3710.8515659373325;3733.4959780429836;3756.154865155318;3829.904252393657;3892.2215556888737;12273.324967126893;12643.557475488315;12652.493900243888;12681.682957925941;12711.173773704864;12526.37368131608;12634.178778379375;12723.872761272454;12831.165974211646;13051.34743262833;9237;9287.674931377505;9506.049395060578;9515.619375224913;9519.095685909828;5421.9186712200135;5480.095784195563;5518.641288316247;5540.501354878081;5721.914171287517;11655.825162622397;11692.883071169179;11711.472983715583;11756.118291128028;11802.52789888395;11725.296482211095;12086.335251561248;12152.696182595506;12300.200486968319;12444.81332779991;7673.194314596887;7817;7819.84360312802;7840.647170877438;7845.5684937329;9019.413738107001;9129.041450647586;9289.071092890657;9323.300752443458;9351.251899847859;7931.325837303893;8025.799355016171;8045.557494337868;8183;8243.752687419386;7477.626118694152;7602.163761986067;7651.464397492122;7698.499597526143;7842.882356764767;7003.4747393944635;7067.434605231466;7102;7104.396126329113;7121.074260346329;5268.315119034501;5318.175682769177;5335.1730481775385;5379.784808607612;5481.2874326338915;5088.211327244283;5108.182690769459;5156.948430515647;5177.2492988515705;5259.605529585373 05:30:00 INFO - PID 16063 | |4;prototype.html;370.8683417386828;381.0568842115766;388.7511992324913;392.62896562748205;395.4108378516011;5366.4633536646415;5574.972125139374;5581.358143813462;5592.972035139824;5608.859778505539;5382.8923421531545;5416.674999500033;5448.128299472085;5459.290292262011;5459.454054594538;4196.454460920076;4212.178625168093;4221.873343799683;4224.831006759726;4230.598093181158;3998.8400463981407;4195.160967806436;4199.580041995791;4227.196832601811;4230.238557059728;4175.874723758292;4182.916341673164;4186.413902053715;4193.559676233982;4194.6853985951175;4169.395437661522;4172.853950111747;4175.144095460423;4193.266178418765;4198.530514319994;3668.321360548307;3673;3676.5771936227407;3683.28176005679;3687.2809802088605;5186.7147306898105;5285.233641122054;5318.361796584396;5325.866853328698;5342.385625653061;4842.15262329091;4842.443119041285;4847.418309802811;4856.1016212000895;4865.270209468573;5579.609427340088;5757;5772.769089236425;5773.769049238025;5785.652860828363;4016.2168377166463;4044.939325910114;4045.2314060328445;4049.8380064797375;4071.7353372030725;5868.325142608612;6089.147519347295;6119.724612392409;6142;6160.414760597736;4116.4769035457075;4150.896227594334;4155.688323375759;4165.708400411942;4182.519010313792;4229.894252643709;4234.089670720809;4238.809253583596;4242.00312926462;4250.3199488081755;4776.95223047772;4786.353842231254;4825.611322395437;4866.902661946812;4872;5728.455796699307;5749.9712501437225;5772.9711351442975;5785.958527465096;5820.796272130539;1456.7960485532035;1473.454821715972;1475.4614565683662;1477.0842077911548;1478.4603619678956;934.3366209990983;980.7106903463496;994.3487016004528;994.7314225159166;1005.9236616820078;5098.923516147261;5117.002184574047;5123.129068058439;5124.180131178993;5125.8718532037;6065.150878876961;6090.36051214625;6091.390860913962;6108.3280839107665;6114.5414093942245;4910.680805747615;5043.268726034705;5088.160453525201;5094.388673359146;5100.38795344561;3216.34065016671;3238.659940706239;3245.464498357792;3250.3011852451837;3252.625948015961;5491.121420572689;5610.747516361774;5630.577706671934;5658;5665.5184309333345;6078.240219972504;6169.537284703665;6201.069839524017;6205.441510264098;6210.968945155245;5537.889242215133;5612.242347283064;5619.297587801525;5620.887582248332;5631.746571404378;5842.2989241289615;5993.670348130895;6033.818985430444;6049.485793707584;6055.666938318434;5930.703464826655;5934.673592952429;5935.9109613356695;5949.39366371086;5950.315713692808;5315.495027972336;5425.403205647297;5485.900466236723;5549.306336707911;5576.7211639417055;4143.948745371061;4144.44050053239;4145.461090058273;4148.357004664282;4151.356539736345;5688.772449102155;6009.579329446897;6017.676111255517;6049.122877182872;6054.515638748801;5792.594518383841;5829.242198514166;5845.912311315248;5857.5313974881055;5867.413258674099;5779.133130030529;5819.272590926134;5827.94172058274;5828.883422331614;5859.839513945147;5660.15097735343;5750.281214848144;5770.711464426678;5778.913316300343;5826.563007774519;5910.763569457174;5913.526917846648;5917.733701983507;5937.554683398849;5956.910646340221;5638.774449021993;5699.088145896802;5748.137779333133;5754.395788442153;5775.306963164448 05:30:00 INFO - PID 16063 | |5;yui.html;691.3777600159856;698.3226270517596;704.9718011279549;732.7726058851424;736.5801493148904;12518.248905065691;12819;12882.098253122273;13105.410256538455;13490.325483725823;12602.306873121976;12710.364481775898;12722.300273484954;12756.61730148097;13450.529231476876;830.7383174300106;839.8152406470567;850.7702920211539;863.9654413823439;877.4910551879894;832.3549249331786;865.1780808232173;872.8821609082788;875.8467268228067;877.3945977275691;908.5638893331205;914.6752902719538;917.4449458077867;918.0085507651753;918.4397517514311;903.5708038681639;905.5562774240641;907.2197909797569;909.5543183839937;910.9134632209963;657.42803760728;668.582136164897;671.013609993309;674.197704731368;674.3896773420014;14239.78640320396;14249.216293103875;14302.141871487744;14447.422103115863;14758.335874885548;13246.337683115902;13757.38091785862;14200.644983875383;14211.452335252723;14292.571222863331;10117;10301.53643086063;10312.175025997902;10521.579136834518;10621;2730.59041143828;2730.8803390609955;2770;2783.6381270434917;2800.915972520828;63863.34032673394;64200.67899660519;64675.67662161706;66502.3349766501;66748;10203.897961020442;10209.642662506925;10218.795624087477;10380.636677716395;10811.945940270405;19065.523361915784;19074.04629768506;19080.332188373617;19119;19197.136128874234;6357.205349331333;6454.354564543508;6567.770128045591;6700.296468870796;6704.530682852153;3313.6355000949875;3334.8165850878436;3342.4652055670977;3375.8987230383127;3436.2096717754557;3730.421784623388;3787.4318852171846;3801;3804.2962052020466;3812.256609961031;709.1915615181267;718.4360277182386;728.8688036153437;734.6657270941708;743.2827321634649;538.3163382504276;555.4639772619361;556.7633755653912;567.8750674851526;568.687222027883;8349.332053435832;8531.232189102886;8763;8897.555122243992;8922.598483068277;541.7101850509958;558.9435966024217;564.3425409398005;568.7043582439771;570.3412558494945;3707.6928422171054;3849.191669749328;3854.1713531590844;3929.980350098231;3983.820728067244;46562.30156547655;47361;48156.759216203696;64251;66107.33892661108;36540;36741.816290918374;41313;42891.35662965058;44832;40112.1977560453;42655.78672106619;44275.33586996198;46689.5331046692;48137.03725925532;8560.828783424422;9046.54767261621;9057.157444445951;9133;9163.587638556148;24268.514629706955;25144.74855251424;26332.73667263303;26367.86816065984;26744.866275668497;31801;32141.67858321387;32180;32576.18559535936;32763.508547372287;11791.587294444653;11792.29246245228;11869.228500147463;12060.819087713517;12520.123591348518;12655.87344126547;12904.225746455242;13081.476740930257;13136.540221092217;13154.60536183916;10663.89336106629;11968.880311196777;12078.879211208126;12095.153339266519;12443.93778031104;11462.4268786562;11965.102617303563;11999.1600587958;12156.209846359958;12732;11367.602133925604;11467.139964502529;11542.82685759731;11557.82663260035;11696.181267311207;8523;8686.695965641173;8692.217700406994;8730.91269087301;8746.212840844355;9697.5151242439;10168.593256269962;10248.38509689421;10267.897321026694;10424.583016679551 05:30:00 INFO - PID 16063 | __end_tp_report 05:30:00 INFO - PID 16063 | __start_cc_report 05:30:00 INFO - PID 16063 | _x_x_mozilla_cycle_collect,386 05:30:00 INFO - PID 16063 | __end_cc_report 05:30:00 INFO - PID 16063 | __startTimestamp1490704200502__endTimestamp 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | ------- Summary: start ------- 05:30:00 INFO - PID 16063 | Number of tests: 6 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | [#0] dojo.html Cycles:165 Average:5124.48 Median:5247.37 stddev:564.26 (10.8%) stddev-sans-first:532.57 05:30:00 INFO - PID 16063 | Values: 2685.7 2710.4 2720.4 2747.4 2754.8 5079.4 5186.6 5207.3 5243.8 5245.0 5184.0 5189.1 5208.3 5245.9 5258.7 4736.0 4750.8 4785.2 4800.2 4832.1 4773.7 4836.0 4849.9 4871.3 4906.3 4638.9 4663.6 4700.2 4769.5 4790.6 4726.8 4758.7 4767.1 4806.7 4812.6 4644.2 4654.0 4709.8 4714.3 4738.5 5002.3 5066.1 5140.4 5149.1 5204.5 5528.0 5545.3 5547.8 5562.8 5569.8 5593.9 5594.5 5635.2 5656.6 5661.0 5034.2 5202.6 5226.9 5262.5 5302.1 5628.2 5665.3 5723.2 5778.8 5817.5 5421.4 5503.4 5537.3 5555.3 5569.8 5466.7 5470.2 5506.1 5537.1 5562.5 5304.0 5485.5 5488.1 5522.4 5553.2 5397.1 5407.3 5412.7 5414.4 5420.4 4633.4 4643.7 4738.5 4781.7 4790.7 4064.1 4066.6 4094.6 4097.7 4110.1 4785.7 4803.0 4813.6 4822.6 4866.2 5346.9 5452.9 5570.0 5620.1 5629.7 4794.9 4801.8 4823.8 4853.1 4855.2 5031.8 5034.9 5044.9 5047.0 5082.5 5006.0 5072.7 5219.7 5244.3 5247.4 5610.0 5611.1 5612.8 5663.2 5675.3 5140.2 5179.7 5207.0 5207.5 5211.7 5348.3 5517.6 5522.1 5531.4 5570.3 5510.6 5611.5 5663.9 5667.8 5688.6 5413.4 5416.1 5418.2 5421.3 5431.2 5220.0 5283.5 5297.3 5340.3 5414.4 5350.8 5356.7 5375.6 5411.6 5423.5 5297.7 5375.8 5484.9 5489.7 5496.5 5321.3 5355.3 5373.7 5380.1 5411.4 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | [#1] ext.html Cycles:180 Average:32238.80 Median:30264.39 stddev:49602.60 (163.9%) stddev-sans-first:49685.00 05:30:00 INFO - PID 16063 | Values: 640.3 696.3 715.8 1162.2 1175.0 32606.5 33192.8 33244.3 33323.2 33347.2 33234.5 33606.5 33734.8 33757.3 33822.8 6741.4 6874.4 7073.1 7325.0 7330.7 7176.4 7202.2 7246.9 7256.7 7257.8 7206.2 7220.3 7264.0 7264.3 7282.9 7224.5 7232.1 7235.3 7337.3 7344.3 7369.1 7384.5 7425.5 7431.0 7455.1 34022.3 34056.5 34075.0 34111.9 34484.3 2593.8 2661.4 2687.9 2701.2 2705.6 20768.2 21008.9 21028.5 21461.8 21518.2 2970.4 2996.5 3005.8 3016.3 3078.2 102459.0 102627.0 128923.0 129044.0 129383.0 36763.4 37119.4 37140.0 37194.4 37314.0 38775.8 39025.8 39182.8 42816.4 42924.8 11897.3 12027.3 12043.1 12076.3 12083.3 9548.7 9548.8 9549.9 9555.7 9557.3 13009.3 13023.3 13053.3 13070.8 13191.5 5517.0 5612.5 5621.7 5721.3 5739.6 856.9 887.2 894.3 897.6 920.1 20234.6 21376.8 21450.2 21472.9 21763.3 950.5 965.9 994.6 996.9 1002.0 10545.6 11304.8 11318.2 11366.8 11392.5 225953.0 227476.0 227483.0 228007.0 228129.0 40514.0 40822.8 40945.8 41081.4 41181.2 1173.3 1235.9 1250.5 1253.2 1254.8 19726.5 20091.5 20924.7 21008.2 21179.7 29908.3 30135.2 30137.2 30260.4 30268.4 204903.0 205975.0 207077.0 207542.0 207938.0 27734.3 28301.3 28379.6 28381.1 28444.7 32212.0 32826.3 33348.0 33797.2 33893.0 26038.1 26413.7 28003.6 28108.0 29207.4 27848.9 27877.3 28097.2 28197.2 28705.9 30731.8 31379.1 31454.7 31459.0 31489.2 27271.6 27524.6 27610.2 27629.3 28873.7 20723.6 21247.7 21603.5 22596.8 22632.2 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | [#2] jquery.html Cycles:180 Average:27042.14 Median:26015.20 stddev:71351.41 (274.3%) stddev-sans-first:71523.55 05:30:00 INFO - PID 16063 | Values: 412.4 418.3 418.8 432.9 441.2 18309.7 18866.1 19043.3 19144.1 19234.5 18311.4 18844.3 18909.4 19631.3 19711.2 5650.5 5769.1 5857.3 5887.8 5892.6 5736.5 5914.9 5917.9 5955.3 5965.9 5669.5 5736.1 5762.9 5773.2 5789.8 5649.4 5710.6 5732.2 5779.1 5868.1 5306.6 5307.5 5309.2 5309.3 5310.2 21819.6 22452.9 22458.0 22480.2 22955.8 1531.2 1536.9 1547.9 1587.9 1592.5 14212.1 14376.9 14383.8 14698.7 15153.2 1613.2 1615.7 1632.4 1646.1 1666.8 87139.0 89810.6 90472.5 90896.5 91639.5 10933.2 11074.9 11169.4 11365.3 11424.7 15714.6 15771.5 15779.1 15835.8 15887.4 7523.0 7706.8 7819.5 7862.7 7898.6 7009.8 7065.0 7097.9 7236.6 7284.8 7053.6 7065.4 7072.7 7122.5 7265.6 1999.9 2035.4 2036.5 2049.1 2235.8 831.3 838.0 862.5 870.7 895.0 16686.3 17271.7 17668.5 17821.0 18090.2 872.6 873.6 876.6 880.7 884.4 9729.0 9793.3 9839.8 9860.9 9879.8 426770.0 426797.0 429091.0 430076.0 436715.0 75696.2 87726.0 89251.6 90968.0 91156.5 1020.7 1039.7 1071.2 1081.8 1084.6 14942.1 15106.5 15317.9 15324.4 15454.1 40929.6 41358.2 41697.0 42223.2 42828.6 51589.2 51881.0 54126.9 54484.7 55597.4 7655.2 7741.5 7911.8 7950.7 8198.6 11511.5 11716.5 11718.3 11780.5 11913.7 9901.7 9911.6 9939.2 10239.1 10341.7 9623.3 9721.2 9759.5 9844.5 9936.6 10009.5 10247.7 10289.2 10334.4 10417.4 7683.2 7692.7 7708.8 7793.2 8072.0 9977.0 10024.5 10120.4 10529.7 10629.1 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | [#3] mootools.html Cycles:180 Average:6240.80 Median:4990.55 stddev:3966.82 (79.5%) stddev-sans-first:3960.31 05:30:00 INFO - PID 16063 | Values: 1262.4 1316.4 1329.0 1334.9 1339.0 9434.2 9731.2 9764.6 9776.7 9954.9 9642.9 9898.0 9902.4 9911.7 9949.8 2213.3 2261.2 2261.6 2265.7 2266.6 2070.4 2132.2 2217.9 2221.3 2230.3 2157.3 2180.2 2272.5 2295.7 2298.4 2108.8 2130.1 2130.3 2134.6 2136.0 1817.3 1883.0 2094.2 2146.6 2148.2 9503.7 9808.0 9815.4 9844.0 9856.8 5209.4 5404.6 5428.9 5439.8 5442.3 3263.3 3329.1 3334.1 3340.6 3410.3 1068.1 1076.9 1087.6 1157.6 1166.8 14358.3 14537.6 14566.0 14577.3 14622.0 4709.5 4848.0 4878.2 4940.3 4951.2 12145.6 12183.0 12193.9 12215.6 12275.9 5594.5 5620.2 5622.2 5649.8 5650.5 4871.4 5029.9 5064.6 5153.9 5210.2 3785.6 3988.1 3991.8 4006.2 4036.6 1172.3 1186.2 1186.4 1192.5 1200.0 692.5 695.2 717.2 721.4 734.5 5359.7 6167.6 6167.8 6179.5 6451.5 809.6 814.9 819.4 838.2 840.8 3710.9 3733.5 3756.2 3829.9 3892.2 12273.3 12643.6 12652.5 12681.7 12711.2 12526.4 12634.2 12723.9 12831.2 13051.3 9237.0 9287.7 9506.0 9515.6 9519.1 5421.9 5480.1 5518.6 5540.5 5721.9 11655.8 11692.9 11711.5 11756.1 11802.5 11725.3 12086.3 12152.7 12300.2 12444.8 7673.2 7817.0 7819.8 7840.6 7845.6 9019.4 9129.0 9289.1 9323.3 9351.3 7931.3 8025.8 8045.6 8183.0 8243.8 7477.6 7602.2 7651.5 7698.5 7842.9 7003.5 7067.4 7102.0 7104.4 7121.1 5268.3 5318.2 5335.2 5379.8 5481.3 5088.2 5108.2 5156.9 5177.2 5259.6 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | [#4] prototype.html Cycles:180 Average:4797.62 Median:5436.77 stddev:1413.37 (26.0%) stddev-sans-first:1377.73 05:30:00 INFO - PID 16063 | Values: 370.9 381.1 388.8 392.6 395.4 5366.5 5575.0 5581.4 5593.0 5608.9 5382.9 5416.7 5448.1 5459.3 5459.5 4196.5 4212.2 4221.9 4224.8 4230.6 3998.8 4195.2 4199.6 4227.2 4230.2 4175.9 4182.9 4186.4 4193.6 4194.7 4169.4 4172.9 4175.1 4193.3 4198.5 3668.3 3673.0 3676.6 3683.3 3687.3 5186.7 5285.2 5318.4 5325.9 5342.4 4842.2 4842.4 4847.4 4856.1 4865.3 5579.6 5757.0 5772.8 5773.8 5785.7 4016.2 4044.9 4045.2 4049.8 4071.7 5868.3 6089.1 6119.7 6142.0 6160.4 4116.5 4150.9 4155.7 4165.7 4182.5 4229.9 4234.1 4238.8 4242.0 4250.3 4777.0 4786.4 4825.6 4866.9 4872.0 5728.5 5750.0 5773.0 5786.0 5820.8 1456.8 1473.5 1475.5 1477.1 1478.5 934.3 980.7 994.3 994.7 1005.9 5098.9 5117.0 5123.1 5124.2 5125.9 6065.2 6090.4 6091.4 6108.3 6114.5 4910.7 5043.3 5088.2 5094.4 5100.4 3216.3 3238.7 3245.5 3250.3 3252.6 5491.1 5610.7 5630.6 5658.0 5665.5 6078.2 6169.5 6201.1 6205.4 6211.0 5537.9 5612.2 5619.3 5620.9 5631.7 5842.3 5993.7 6033.8 6049.5 6055.7 5930.7 5934.7 5935.9 5949.4 5950.3 5315.5 5425.4 5485.9 5549.3 5576.7 4143.9 4144.4 4145.5 4148.4 4151.4 5688.8 6009.6 6017.7 6049.1 6054.5 5792.6 5829.2 5845.9 5857.5 5867.4 5779.1 5819.3 5827.9 5828.9 5859.8 5660.2 5750.3 5770.7 5778.9 5826.6 5910.8 5913.5 5917.7 5937.6 5956.9 5638.8 5699.1 5748.1 5754.4 5775.3 05:30:00 INFO - PID 16063 | 05:30:00 INFO - PID 16063 | [#5] yui.html Cycles:180 Average:13351.16 Median:3808.28 stddev:15583.23 (409.2%) stddev-sans-first:15597.94 05:30:00 INFO - PID 16063 | Values: 691.4 698.3 705.0 732.8 736.6 12518.2 12819.0 12882.1 13105.4 13490.3 12602.3 12710.4 12722.3 12756.6 13450.5 830.7 839.8 850.8 864.0 877.5 832.4 865.2 872.9 875.8 877.4 908.6 914.7 917.4 918.0 918.4 903.6 905.6 907.2 909.6 910.9 657.4 668.6 671.0 674.2 674.4 14239.8 14249.2 14302.1 14447.4 14758.3 13246.3 13757.4 14200.6 14211.5 14292.6 10117.0 10301.5 10312.2 10521.6 10621.0 2730.6 2730.9 2770.0 2783.6 2800.9 63863.3 64200.7 64675.7 66502.3 66748.0 10203.9 10209.6 10218.8 10380.6 10811.9 19065.5 19074.0 19080.3 19119.0 19197.1 6357.2 6454.4 6567.8 6700.3 6704.5 3313.6 3334.8 3342.5 3375.9 3436.2 3730.4 3787.4 3801.0 3804.3 3812.3 709.2 718.4 728.9 734.7 743.3 538.3 555.5 556.8 567.9 568.7 8349.3 8531.2 8763.0 8897.6 8922.6 541.7 558.9 564.3 568.7 570.3 3707.7 3849.2 3854.2 3930.0 3983.8 46562.3 47361.0 48156.8 64251.0 66107.3 36540.0 36741.8 41313.0 42891.4 44832.0 40112.2 42655.8 44275.3 46689.5 48137.0 8560.8 9046.5 9057.2 9133.0 9163.6 24268.5 25144.7 26332.7 26367.9 26744.9 31801.0 32141.7 32180.0 32576.2 32763.5 11791.6 11792.3 11869.2 12060.8 12520.1 12655.9 12904.2 13081.5 13136.5 13154.6 10663.9 11968.9 12078.9 12095.2 12443.9 11462.4 11965.1 11999.2 12156.2 12732.0 11367.6 11467.1 11542.8 11557.8 11696.2 8523.0 8686.7 8692.2 8730.9 8746.2 9697.5 10168.6 10248.4 10267.9 10424.6 05:30:00 INFO - PID 16063 | -------- Summary: end -------- 05:30:00 INFO - PID 16063 | 05:30:01 INFO - TEST-INFO | 16063: exit 0 05:30:01 INFO - TEST-OK | dromaeo_css | took 1122113ms 05:30:01 INFO - TEST-START | kraken 05:30:01 INFO - operating with platform_type : linux_ 05:30:01 INFO - Initialising browser for kraken test... 05:30:01 INFO - TEST-INFO | started process 16442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5sBQy7/profile http://localhost:37310/getInfo.html) 05:30:05 INFO - PID 16442 | __metrics Screen width/height:1600/1200 05:30:05 INFO - PID 16442 | colorDepth:24 05:30:05 INFO - PID 16442 | Browser inner width/height: 1024/697 05:30:05 INFO - PID 16442 | __metrics 05:30:06 ERROR - PID 16442 | 1490704206432 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 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6376:36 < canClose@browser.js:5227:12 < forceQuit@jar:file:///tmp/tmp5sBQy7/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmp5sBQy7/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmp5sBQy7/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmp5sBQy7/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:30:06 ERROR - PID 16442 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:30:07 INFO - TEST-INFO | 16442: exit 0 05:30:07 INFO - Browser initialized. 05:30:07 INFO - Running cycle 1/1 for kraken test... 05:30:07 INFO - TEST-INFO | started process 16570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5sBQy7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:19 INFO - PID 16570 | RSS: Main: 155987968 05:30:19 INFO - PID 16570 | 05:30:22 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37310/tests/kraken/driver.html?testName=audio-beat-detection) 05:30:23 INFO - PID 16570 | RSS: Main: 162271232 05:30:23 INFO - PID 16570 | 05:30:26 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37310/tests/kraken/driver.html?testName=audio-dft) 05:30:26 INFO - PID 16570 | RSS: Main: 161693696 05:30:26 INFO - PID 16570 | 05:30:29 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37310/tests/kraken/driver.html?testName=audio-fft) 05:30:30 INFO - PID 16570 | RSS: Main: 158195712 05:30:30 INFO - PID 16570 | 05:30:32 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37310/tests/kraken/driver.html?testName=audio-oscillator) 05:30:33 INFO - PID 16570 | RSS: Main: 159154176 05:30:33 INFO - PID 16570 | 05:30:35 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37310/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:35 INFO - PID 16570 | RSS: Main: 159404032 05:30:35 INFO - PID 16570 | 05:30:39 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37310/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:39 INFO - PID 16570 | RSS: Main: 164343808 05:30:39 INFO - PID 16570 | 05:30:43 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37310/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:43 INFO - PID 16570 | RSS: Main: 161755136 05:30:43 INFO - PID 16570 | 05:30:46 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37310/tests/kraken/driver.html?testName=json-parse-financial) 05:30:47 INFO - PID 16570 | RSS: Main: 163545088 05:30:47 INFO - PID 16570 | 05:30:49 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37310/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:30:49 INFO - PID 16570 | RSS: Main: 163872768 05:30:49 INFO - PID 16570 | 05:30:51 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:30:52 INFO - PID 16570 | RSS: Main: 163786752 05:30:52 INFO - PID 16570 | 05:30:54 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:30:55 INFO - PID 16570 | RSS: Main: 163860480 05:30:55 INFO - PID 16570 | 05:30:58 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:30:58 INFO - PID 16570 | RSS: Main: 164188160 05:30:58 INFO - PID 16570 | 05:31:02 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:02 INFO - PID 16570 | RSS: Main: 163971072 05:31:02 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | Cycle 1(1): loaded http://localhost:37310/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37310/tests/kraken/driver.html?testName=ai-astar) 05:31:04 INFO - PID 16570 | RSS: Main: 165752832 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | __start_tp_report 05:31:04 INFO - PID 16570 | _x_x_mozilla_page_load 05:31:04 INFO - PID 16570 | _x_x_mozilla_page_load_details 05:31:04 INFO - PID 16570 | |i|pagename|runs| 05:31:04 INFO - PID 16570 | |0;ai-astar;104;112;112;113;111;111;113;113;113;112 05:31:04 INFO - PID 16570 | |1;audio-beat-detection;158;125;126;180;126;125;131;125;149;125 05:31:04 INFO - PID 16570 | |2;audio-dft;167;166;169;172;170;170;166;170;167;164 05:31:04 INFO - PID 16570 | |3;audio-fft;75;75;77;107;78;77;77;76;103;76 05:31:04 INFO - PID 16570 | |4;audio-oscillator;85;85;113;85;85;85;97;84;84;111 05:31:04 INFO - PID 16570 | |5;imaging-gaussian-blur;115;114;116;114;115;121;115;114;115;114 05:31:04 INFO - PID 16570 | |6;imaging-darkroom;152;152;153;151;150;154;152;151;152;152 05:31:04 INFO - PID 16570 | |7;imaging-desaturate;95;95;94;95;95;95;95;96;95;95 05:31:04 INFO - PID 16570 | |8;json-parse-financial;68;68;68;69;69;69;70;69;69;69 05:31:04 INFO - PID 16570 | |9;json-stringify-tinderbox;55;55;55;56;55;57;56;55;55;58 05:31:04 INFO - PID 16570 | |10;stanford-crypto-aes;83;84;83;91;84;83;85;94;86;84 05:31:04 INFO - PID 16570 | |11;stanford-crypto-ccm;145;141;136;139;152;147;140;137;184;138 05:31:04 INFO - PID 16570 | |12;stanford-crypto-pbkdf2;188;194;197;196;189;207;211;197;200;199 05:31:04 INFO - PID 16570 | |13;stanford-crypto-sha256-iterative;75;71;72;73;73;73;72;71;72;75 05:31:04 INFO - PID 16570 | __end_tp_report 05:31:04 INFO - PID 16570 | __start_cc_report 05:31:04 INFO - PID 16570 | _x_x_mozilla_cycle_collect,876 05:31:04 INFO - PID 16570 | __end_cc_report 05:31:04 INFO - PID 16570 | __startTimestamp1490704264856__endTimestamp 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | ------- Summary: start ------- 05:31:04 INFO - PID 16570 | Number of tests: 14 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:2.72 (2.4%) stddev-sans-first:0.83 05:31:04 INFO - PID 16570 | Values: 104.0 112.0 112.0 113.0 111.0 111.0 113.0 113.0 113.0 112.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:128.50 stddev:19.11 (14.9%) stddev-sans-first:18.70 05:31:04 INFO - PID 16570 | Values: 158.0 125.0 126.0 180.0 126.0 125.0 131.0 125.0 149.0 125.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#2] audio-dft Cycles:10 Average:168.10 Median:169.50 stddev:2.47 (1.5%) stddev-sans-first:2.59 05:31:04 INFO - PID 16570 | Values: 167.0 166.0 169.0 172.0 170.0 170.0 166.0 170.0 167.0 164.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.50 stddev:12.14 (15.9%) stddev-sans-first:12.60 05:31:04 INFO - PID 16570 | Values: 75.0 75.0 77.0 107.0 78.0 77.0 77.0 76.0 103.0 76.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:85.00 stddev:11.53 (13.6%) stddev-sans-first:11.99 05:31:04 INFO - PID 16570 | Values: 85.0 85.0 113.0 85.0 85.0 85.0 97.0 84.0 84.0 111.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#5] imaging-gaussian-blur Cycles:10 Average:115.30 Median:115.00 stddev:2.11 (1.8%) stddev-sans-first:2.24 05:31:04 INFO - PID 16570 | Values: 115.0 114.0 116.0 114.0 115.0 121.0 115.0 114.0 115.0 114.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#6] imaging-darkroom Cycles:10 Average:151.90 Median:152.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:31:04 INFO - PID 16570 | Values: 152.0 152.0 153.0 151.0 150.0 154.0 152.0 151.0 152.0 152.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 05:31:04 INFO - PID 16570 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:69.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 05:31:04 INFO - PID 16570 | Values: 68.0 68.0 68.0 69.0 69.0 69.0 70.0 69.0 69.0 69.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#9] json-stringify-tinderbox Cycles:10 Average:55.70 Median:55.50 stddev:1.06 (1.9%) stddev-sans-first:1.09 05:31:04 INFO - PID 16570 | Values: 55.0 55.0 55.0 56.0 55.0 57.0 56.0 55.0 55.0 58.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:84.50 stddev:3.77 (4.5%) stddev-sans-first:3.87 05:31:04 INFO - PID 16570 | Values: 83.0 84.0 83.0 91.0 84.0 83.0 85.0 94.0 86.0 84.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:143.00 stddev:14.29 (10.0%) stddev-sans-first:15.15 05:31:04 INFO - PID 16570 | Values: 145.0 141.0 136.0 139.0 152.0 147.0 140.0 137.0 184.0 138.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.80 Median:198.00 stddev:7.13 (3.6%) stddev-sans-first:6.62 05:31:04 INFO - PID 16570 | Values: 188.0 194.0 197.0 196.0 189.0 207.0 211.0 197.0 200.0 199.0 05:31:04 INFO - PID 16570 | 05:31:04 INFO - PID 16570 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:73.00 stddev:1.42 (1.9%) stddev-sans-first:1.24 05:31:04 INFO - PID 16570 | Values: 75.0 71.0 72.0 73.0 73.0 73.0 72.0 71.0 72.0 75.0 05:31:04 INFO - PID 16570 | -------- Summary: end -------- 05:31:04 INFO - PID 16570 | 05:31:06 INFO - TEST-INFO | 16570: exit 0 05:31:06 INFO - TEST-OK | kraken | took 64904ms 05:31:06 INFO - SUITE-END | took 1187s 05:31:06 INFO - Completed test suite (00:19:47) 05:31:06 INFO - javascript benchmark 05:31:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6619.622706825965, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2685.6642919635046, 2710.4037111835414, 2720.4015116674336, 2747.395572973945, 2754.8347099174043, 5079.390473143228, 5186.585073194149, 5207.27098206251, 5243.79024839006, 5244.973775131124, 5183.999999999991, 5189.143791274435, 5208.296879921209, 5245.86885327867, 5258.737063146837, 4735.9526404736025, 4750.809967601292, 4785.186518291881, 4800.183968725308, 4832.05774873898, 4773.737444440554, 4835.975820120912, 4849.854504364875, 4871.293662418965, 4906.288588154715, 4638.8608341749805, 4663.556962088596, 4700.247960326331, 4769.4515130760055, 4790.568848803624, 4726.83456079039, 4758.714477131383, 4767.1419144553965, 4806.711597304138, 4812.5668689818085, 4644.233701439259, 4653.953460465385, 4709.764511774398, 4714.339992401066, 4738.549837765407, 5002.349694539686, 5066.088104141253, 5140.40885298188, 5149.0989076911765, 5204.505571970694, 5528.032594296023, 5545.306836645419, 5547.833564993057, 5562.7774889004395, 5569.777208911639, 5593.888122237532, 5594.524465420444, 5635.154726791015, 5656.604037717361, 5661.0, 5034.244863270465, 5202.609804264695, 5226.8954620908125, 5262.473752624784, 5302.09864323066, 5628.183913332544, 5665.263515742927, 5723.170140329629, 5778.8266352009505, 5817.505512031525, 5421.430749771296, 5503.367112782082, 5537.252470916455, 5555.30558680165, 5569.832905012856, 5466.6993315367245, 5470.234167216593, 5506.1190209566275, 5537.141743029837, 5562.499375056183, 5304.0, 5485.451454854483, 5488.067028605146, 5522.420145884705, 5553.222548843165, 5397.055515284809, 5407.297051383295, 5412.702301373383, 5414.377346605112, 5420.403755586883, 4633.351330813689, 4643.698159619614, 4738.5261473853025, 4781.665283430127, 4790.664653474293, 4064.1058967027207, 4066.5933406659688, 4094.590540945941, 4097.672186225095, 4110.0752330725345, 4785.665003449725, 4802.975985120052, 4813.614910807197, 4822.590079843252, 4866.172750632401, 5346.91979620306, 5452.945470545244, 5569.972150139304, 5620.100783874642, 5629.718514074362, 4794.880127996758, 4801.783919723621, 4823.807047718122, 4853.077915196172, 4855.198892182821, 5031.849044528597, 5034.949650503448, 5044.876514955542, 5047.041062198171, 5082.542571168613, 5006.048850718352, 5072.695638261716, 5219.660722053054, 5244.265802787537, 5247.370315562004, 5610.0, 5611.074172761449, 5612.803551875828, 5663.207150998781, 5675.31896172462, 5140.177571588528, 5179.715115668674, 5206.973965130302, 5207.505286997729, 5211.687298762086, 5348.331458567679, 5517.613767036269, 5522.06124958766, 5531.363893152339, 5570.3315602128005, 5510.641808282448, 5611.4949654529655, 5663.8867222656145, 5667.801626943202, 5688.5733569981585, 5413.377461591967, 5416.133418652997, 5418.187271909245, 5421.349438067457, 5431.23962645222, 5220.034293655686, 5283.498067683564, 5297.337832770903, 5340.305760251143, 5414.35027796667, 5350.839474815762, 5356.651817631841, 5375.623706340675, 5411.621186516906, 5423.457654234545, 5297.708626025452, 5375.81184658535, 5484.862878428071, 5489.725513724218, 5496.4503549646315, 5321.308229930084, 5355.250264962986, 5373.677579345252, 5380.058489764354, 5411.350637923317], "value": 5085.42264650698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.2732898160589, 696.2584847137798, 715.7602203261908, 1162.238733629473, 1175.0247294745361, 32606.510902336486, 33192.83403582979, 33244.33511329772, 33323.16692082699, 33347.16632084199, 33234.501482477724, 33606.49590256142, 33734.83132584333, 33757.32485350298, 33822.8308858456, 6741.426978706803, 6874.415674667659, 7073.080499535054, 7325.0, 7330.743423980161, 7176.390006849424, 7202.171750248736, 7246.891296630555, 7256.746013889515, 7257.818554536126, 7206.243344448836, 7220.3140701633265, 7263.963680181591, 7264.309890560415, 7282.927170728278, 7224.530405523677, 7232.059832221804, 7235.312645298688, 7337.266273372674, 7344.339009489172, 7369.115706115301, 7384.483086183969, 7425.480216384855, 7430.96284518574, 7455.142658594277, 34022.319553609035, 34056.48915266273, 34075.0, 34111.929926306504, 34484.31031379384, 2593.766561009518, 2661.3612732943952, 2687.87904544296, 2701.230149407429, 2705.580635001578, 20768.169273229054, 21008.894955525124, 21028.474288142766, 21461.785382146292, 21518.246861359934, 2970.4207679502506, 2996.535536991748, 3005.8046226995175, 3016.306249562591, 3078.184281165493, 102459.0, 102627.0, 128923.0, 129044.0, 129383.0, 36763.448548271794, 37119.4432083519, 37140.0, 37194.44208336877, 37314.0, 38775.80612096978, 39025.82922512329, 39182.80408597939, 42816.357754633704, 42924.78537607292, 11897.286162830258, 12027.338496382783, 12043.096767742454, 12076.275423474444, 12083.275003499819, 9548.713538593853, 9548.761280967892, 9549.856752148724, 9555.713328600154, 9557.330986830993, 13009.349532523336, 13023.283719395335, 13053.34733263333, 13070.803937940938, 13191.538296159588, 5517.0, 5612.494875461228, 5621.690807005572, 5721.342045664802, 5739.598228124075, 856.8800367948493, 887.1572006593761, 894.2711689972651, 897.5871099294267, 920.0615372320196, 20234.59530809405, 21376.78623213779, 21450.249241276793, 21472.892635536722, 21763.347099587038, 950.4677380666849, 965.9082387173207, 994.5533986393552, 996.8704068471052, 1001.9599216031277, 10545.578176872992, 11304.830427543593, 11318.207725459139, 11366.829497557543, 11392.544298228144, 225953.0, 227476.0, 227483.0, 228007.0, 228129.0, 40514.0, 40822.79588602098, 40945.79527102345, 41081.38377924333, 41181.1763764723, 1173.3040921098607, 1235.9073069519823, 1250.5185503581188, 1253.1603825436796, 1254.830597869276, 19726.506837329183, 20091.497712556717, 20924.686129707763, 21008.159673613496, 21179.68230476575, 29908.252293692833, 30135.246618833826, 30137.246568835955, 30260.394792104475, 30268.394632106792, 204903.0, 205975.0, 207077.0, 207542.0, 207938.0, 27734.306642334104, 28301.292467688472, 28379.574306385835, 28381.148565543066, 28444.715552844205, 32212.0, 32826.34347312993, 33348.0, 33797.15507112243, 33893.0, 26038.08866689657, 26413.735862641897, 28003.57994630123, 28108.0, 29207.41585168242, 27848.860755696092, 27877.303067423476, 28097.157085287476, 28197.15408537747, 28705.856470717514, 30731.846340768152, 31379.05862824119, 31454.685453146092, 31459.0, 31489.21276968094, 27271.59092613652, 27524.58713119265, 27610.16822056092, 27629.30926726848, 28873.711262887104, 20723.585528289652, 21247.68128478105, 21603.459913502287, 22596.774032259465, 22632.207872724375], "value": 14548.279127274582, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.3608406969197, 418.3097888484003, 418.81153480933585, 432.90043290043286, 441.1683975705794, 18309.711286089238, 18866.056697165157, 19043.333483328053, 19144.13851376688, 19234.51913702158, 18311.35910243142, 18844.34044808432, 18909.43271701851, 19631.31290404836, 19711.211551537923, 5650.463205995424, 5769.1346298055205, 5857.326407463132, 5887.793927212549, 5892.587518873681, 5736.455036771521, 5914.852128696774, 5917.911231331534, 5955.315138759033, 5965.910511342333, 5669.461401166903, 5736.139579063153, 5762.884742305151, 5773.220615216955, 5789.7684092636455, 5649.435056494318, 5710.5717071219615, 5732.22614946981, 5779.104238842965, 5868.149118377854, 5306.628536002481, 5307.495787900143, 5309.150535914274, 5309.33633295838, 5310.150375939831, 21819.563608727894, 22452.887735561217, 22458.0, 22480.213192538347, 22955.770442295696, 1531.2420351925757, 1536.9385224591003, 1547.8761699064048, 1587.928543215558, 1592.5222433269973, 14212.076215046092, 14376.92811535946, 14383.78424323636, 14698.706025879423, 15153.166575838324, 1613.16115619877, 1615.7253266944647, 1632.3960134750218, 1646.1193261604983, 1666.7999840019274, 87139.0, 89810.5509472455, 90472.54763726139, 90896.545517272, 91639.54180229123, 10933.234673572773, 11074.944625276821, 11169.385683787306, 11365.261258018203, 11424.714382140513, 15714.607134821721, 15771.526854194393, 15779.132147731756, 15835.762463563056, 15887.364505419653, 7522.962385188039, 7706.845863082707, 7819.491733037335, 7862.680509249785, 7898.60506974649, 7009.754658586925, 7064.964675176694, 7097.858042839218, 7236.638168091575, 7284.781456556312, 7053.576785392893, 7065.431956861855, 7072.681729322193, 7122.537035092806, 7265.636718164071, 1999.8700084494462, 2035.409731177942, 2036.521417466894, 2049.139361468187, 2235.7865712944067, 831.2518733140222, 837.9664813407395, 862.4868203418956, 870.6604424274537, 894.9776255593662, 16686.332546697995, 17271.74092388615, 17668.469945901645, 17821.0, 18090.18594163266, 872.5724395046509, 873.6287077991828, 876.5595039470054, 880.7181701855345, 884.429542944797, 9728.978457261886, 9793.265505087147, 9839.803203936024, 9860.901390985999, 9879.802403952024, 426770.0, 426797.0, 429091.0, 430076.0, 436715.0, 75696.24303757002, 87726.0, 89251.55374223216, 90968.0, 91156.54421727982, 1020.7346090016649, 1039.6880935719255, 1071.2180108520824, 1081.7944590527775, 1084.5607528950788, 14942.086677688227, 15106.471273505374, 15317.923410382877, 15324.387024519341, 15454.072755634697, 40929.59070409258, 41358.1728365437, 41697.0, 42223.1555368897, 42828.57171428246, 51589.226161608356, 51881.0, 54126.903984682256, 54484.72757636187, 55597.44402556084, 7655.157932627518, 7741.496802707806, 7911.802204944923, 7950.681972721258, 8198.59007049657, 11511.481983310938, 11716.472758726048, 11718.296902185895, 11780.469878855301, 11913.702157446132, 9901.653442129491, 9911.553980071058, 9939.204863610748, 10239.129673977795, 10341.689749307532, 9623.326367154232, 9721.173700235558, 9759.463229522444, 9844.507774611384, 9936.602535898483, 10009.5495702695, 10247.692569222936, 10289.228307877089, 10334.431606261727, 10417.374957502574, 7683.154852966058, 7692.730754423568, 7708.8458230836195, 7793.220677932161, 8071.959640201761, 9976.950115249378, 10024.498775061364, 10120.392776433437, 10529.73675658084, 10629.149668026384], "value": 8865.849097033291, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.3751243134648, 1316.387879635969, 1328.966775830604, 1334.9399277032533, 1339.0492750147391, 9434.24526037917, 9731.172850307714, 9764.609415623376, 9776.706698799038, 9954.900450995488, 9642.855357169648, 9898.0, 9902.35634683745, 9911.702648920525, 9949.751256218598, 2213.3359992002415, 2261.197274967379, 2261.6494443361307, 2265.728112626487, 2266.626006708891, 2070.3788863340937, 2132.1791110422523, 2217.933461996143, 2221.3224966385246, 2230.275160572812, 2157.255746767363, 2180.2042254577123, 2272.5000499889998, 2295.6671282664006, 2298.37943755186, 2108.7891210878865, 2130.1160018592423, 2130.3289463818846, 2134.6264403729365, 2135.957280854382, 1817.273090763705, 1882.962340753191, 2094.2042024030925, 2146.5921474919714, 2148.1622167354744, 9503.667371642028, 9808.0, 9815.36200146988, 9844.0, 9856.802863942752, 5209.374875014984, 5404.621676482609, 5428.891422171534, 5439.809606663787, 5442.319710036246, 3263.321994053097, 3329.1344250494803, 3334.0664613908143, 3340.5657264555452, 3410.28384039352, 1068.086785798147, 1076.9084627806646, 1087.5589843457371, 1157.6411312493153, 1166.7608140331213, 14358.282085895768, 14537.636559086002, 14566.0, 14577.344019519252, 14622.0, 4709.529047095263, 4847.95152048482, 4878.195097808893, 4940.308356830046, 4951.207806750903, 12145.635630931087, 12183.0, 12193.890557907855, 12215.633530994084, 12275.93862030702, 5594.496495315441, 5620.213170156181, 5622.184783206412, 5649.774009039593, 5650.463205995424, 4871.3667223260745, 5029.899402011939, 5064.594832413398, 5153.948460515422, 5210.192420174879, 3785.602511736228, 3988.142549351903, 3991.84032638697, 4006.238814625212, 4036.616521430459, 1172.3376292394769, 1186.1993154620598, 1186.4186548591128, 1192.4753108632174, 1199.9880001200052, 692.5221597097986, 695.2387136085978, 717.2218143314562, 721.3579913876654, 734.4895297768106, 5359.722118099792, 6167.599106058092, 6167.84580385494, 6179.474744646665, 6451.516136289797, 809.5790189101634, 814.8573999550055, 819.3854609043218, 838.2078935406105, 840.82342708032, 3710.8515659373325, 3733.4959780429836, 3756.154865155318, 3829.904252393657, 3892.2215556888737, 12273.324967126893, 12643.557475488315, 12652.493900243888, 12681.682957925941, 12711.173773704864, 12526.37368131608, 12634.178778379375, 12723.872761272454, 12831.165974211646, 13051.34743262833, 9237.0, 9287.674931377505, 9506.049395060578, 9515.619375224913, 9519.095685909828, 5421.9186712200135, 5480.095784195563, 5518.641288316247, 5540.501354878081, 5721.914171287517, 11655.825162622397, 11692.883071169179, 11711.472983715583, 11756.118291128028, 11802.52789888395, 11725.296482211095, 12086.335251561248, 12152.696182595506, 12300.200486968319, 12444.81332779991, 7673.194314596887, 7817.0, 7819.84360312802, 7840.647170877438, 7845.5684937329, 9019.413738107001, 9129.041450647586, 9289.071092890657, 9323.300752443458, 9351.251899847859, 7931.325837303893, 8025.799355016171, 8045.557494337868, 8183.0, 8243.752687419386, 7477.626118694152, 7602.163761986067, 7651.464397492122, 7698.499597526143, 7842.882356764767, 7003.4747393944635, 7067.434605231466, 7102.0, 7104.396126329113, 7121.074260346329, 5268.315119034501, 5318.175682769177, 5335.1730481775385, 5379.784808607612, 5481.2874326338915, 5088.211327244283, 5108.182690769459, 5156.948430515647, 5177.2492988515705, 5259.605529585373], "value": 4724.117563568457, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.8683417386828, 381.0568842115766, 388.7511992324913, 392.62896562748205, 395.4108378516011, 5366.4633536646415, 5574.972125139374, 5581.358143813462, 5592.972035139824, 5608.859778505539, 5382.8923421531545, 5416.674999500033, 5448.128299472085, 5459.290292262011, 5459.454054594538, 4196.454460920076, 4212.178625168093, 4221.873343799683, 4224.831006759726, 4230.598093181158, 3998.8400463981407, 4195.160967806436, 4199.580041995791, 4227.196832601811, 4230.238557059728, 4175.874723758292, 4182.916341673164, 4186.413902053715, 4193.559676233982, 4194.6853985951175, 4169.395437661522, 4172.853950111747, 4175.144095460423, 4193.266178418765, 4198.530514319994, 3668.321360548307, 3673.0, 3676.5771936227407, 3683.28176005679, 3687.2809802088605, 5186.7147306898105, 5285.233641122054, 5318.361796584396, 5325.866853328698, 5342.385625653061, 4842.15262329091, 4842.443119041285, 4847.418309802811, 4856.1016212000895, 4865.270209468573, 5579.609427340088, 5757.0, 5772.769089236425, 5773.769049238025, 5785.652860828363, 4016.2168377166463, 4044.939325910114, 4045.2314060328445, 4049.8380064797375, 4071.7353372030725, 5868.325142608612, 6089.147519347295, 6119.724612392409, 6142.0, 6160.414760597736, 4116.4769035457075, 4150.896227594334, 4155.688323375759, 4165.708400411942, 4182.519010313792, 4229.894252643709, 4234.089670720809, 4238.809253583596, 4242.00312926462, 4250.3199488081755, 4776.95223047772, 4786.353842231254, 4825.611322395437, 4866.902661946812, 4872.0, 5728.455796699307, 5749.9712501437225, 5772.9711351442975, 5785.958527465096, 5820.796272130539, 1456.7960485532035, 1473.454821715972, 1475.4614565683662, 1477.0842077911548, 1478.4603619678956, 934.3366209990983, 980.7106903463496, 994.3487016004528, 994.7314225159166, 1005.9236616820078, 5098.923516147261, 5117.002184574047, 5123.129068058439, 5124.180131178993, 5125.8718532037, 6065.150878876961, 6090.36051214625, 6091.390860913962, 6108.3280839107665, 6114.5414093942245, 4910.680805747615, 5043.268726034705, 5088.160453525201, 5094.388673359146, 5100.38795344561, 3216.34065016671, 3238.659940706239, 3245.464498357792, 3250.3011852451837, 3252.625948015961, 5491.121420572689, 5610.747516361774, 5630.577706671934, 5658.0, 5665.5184309333345, 6078.240219972504, 6169.537284703665, 6201.069839524017, 6205.441510264098, 6210.968945155245, 5537.889242215133, 5612.242347283064, 5619.297587801525, 5620.887582248332, 5631.746571404378, 5842.2989241289615, 5993.670348130895, 6033.818985430444, 6049.485793707584, 6055.666938318434, 5930.703464826655, 5934.673592952429, 5935.9109613356695, 5949.39366371086, 5950.315713692808, 5315.495027972336, 5425.403205647297, 5485.900466236723, 5549.306336707911, 5576.7211639417055, 4143.948745371061, 4144.44050053239, 4145.461090058273, 4148.357004664282, 4151.356539736345, 5688.772449102155, 6009.579329446897, 6017.676111255517, 6049.122877182872, 6054.515638748801, 5792.594518383841, 5829.242198514166, 5845.912311315248, 5857.5313974881055, 5867.413258674099, 5779.133130030529, 5819.272590926134, 5827.94172058274, 5828.883422331614, 5859.839513945147, 5660.15097735343, 5750.281214848144, 5770.711464426678, 5778.913316300343, 5826.563007774519, 5910.763569457174, 5913.526917846648, 5917.733701983507, 5937.554683398849, 5956.910646340221, 5638.774449021993, 5699.088145896802, 5748.137779333133, 5754.395788442153, 5775.306963164448], "value": 4362.457739497252, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.3777600159856, 698.3226270517596, 704.9718011279549, 732.7726058851424, 736.5801493148904, 12518.248905065691, 12819.0, 12882.098253122273, 13105.410256538455, 13490.325483725823, 12602.306873121976, 12710.364481775898, 12722.300273484954, 12756.61730148097, 13450.529231476876, 830.7383174300106, 839.8152406470567, 850.7702920211539, 863.9654413823439, 877.4910551879894, 832.3549249331786, 865.1780808232173, 872.8821609082788, 875.8467268228067, 877.3945977275691, 908.5638893331205, 914.6752902719538, 917.4449458077867, 918.0085507651753, 918.4397517514311, 903.5708038681639, 905.5562774240641, 907.2197909797569, 909.5543183839937, 910.9134632209963, 657.42803760728, 668.582136164897, 671.013609993309, 674.197704731368, 674.3896773420014, 14239.78640320396, 14249.216293103875, 14302.141871487744, 14447.422103115863, 14758.335874885548, 13246.337683115902, 13757.38091785862, 14200.644983875383, 14211.452335252723, 14292.571222863331, 10117.0, 10301.53643086063, 10312.175025997902, 10521.579136834518, 10621.0, 2730.59041143828, 2730.8803390609955, 2770.0, 2783.6381270434917, 2800.915972520828, 63863.34032673394, 64200.67899660519, 64675.67662161706, 66502.3349766501, 66748.0, 10203.897961020442, 10209.642662506925, 10218.795624087477, 10380.636677716395, 10811.945940270405, 19065.523361915784, 19074.04629768506, 19080.332188373617, 19119.0, 19197.136128874234, 6357.205349331333, 6454.354564543508, 6567.770128045591, 6700.296468870796, 6704.530682852153, 3313.6355000949875, 3334.8165850878436, 3342.4652055670977, 3375.8987230383127, 3436.2096717754557, 3730.421784623388, 3787.4318852171846, 3801.0, 3804.2962052020466, 3812.256609961031, 709.1915615181267, 718.4360277182386, 728.8688036153437, 734.6657270941708, 743.2827321634649, 538.3163382504276, 555.4639772619361, 556.7633755653912, 567.8750674851526, 568.687222027883, 8349.332053435832, 8531.232189102886, 8763.0, 8897.555122243992, 8922.598483068277, 541.7101850509958, 558.9435966024217, 564.3425409398005, 568.7043582439771, 570.3412558494945, 3707.6928422171054, 3849.191669749328, 3854.1713531590844, 3929.980350098231, 3983.820728067244, 46562.30156547655, 47361.0, 48156.759216203696, 64251.0, 66107.33892661108, 36540.0, 36741.816290918374, 41313.0, 42891.35662965058, 44832.0, 40112.1977560453, 42655.78672106619, 44275.33586996198, 46689.5331046692, 48137.03725925532, 8560.828783424422, 9046.54767261621, 9057.157444445951, 9133.0, 9163.587638556148, 24268.514629706955, 25144.74855251424, 26332.73667263303, 26367.86816065984, 26744.866275668497, 31801.0, 32141.67858321387, 32180.0, 32576.18559535936, 32763.508547372287, 11791.587294444653, 11792.29246245228, 11869.228500147463, 12060.819087713517, 12520.123591348518, 12655.87344126547, 12904.225746455242, 13081.476740930257, 13136.540221092217, 13154.60536183916, 10663.89336106629, 11968.880311196777, 12078.879211208126, 12095.153339266519, 12443.93778031104, 11462.4268786562, 11965.102617303563, 11999.1600587958, 12156.209846359958, 12732.0, 11367.602133925604, 11467.139964502529, 11542.82685759731, 11557.82663260035, 11696.181267311207, 8523.0, 8686.695965641173, 8692.217700406994, 8730.91269087301, 8746.212840844355, 9697.5151242439, 10168.593256269962, 10248.38509689421, 10267.897321026694, 10424.583016679551], "value": 6223.81437389217, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 113.0, 111.0, 111.0, 113.0, 113.0, 113.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 126.0, 180.0, 126.0, 125.0, 131.0, 125.0, 149.0, 125.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 169.0, 172.0, 170.0, 170.0, 166.0, 170.0, 167.0, 164.0], "value": 168.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 107.0, 78.0, 77.0, 77.0, 76.0, 103.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 113.0, 85.0, 85.0, 85.0, 97.0, 84.0, 84.0, 111.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 114.0, 115.0, 121.0, 115.0, 114.0, 115.0, 114.0], "value": 115.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 153.0, 151.0, 150.0, 154.0, 152.0, 151.0, 152.0, 152.0], "value": 151.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 69.0, 69.0, 70.0, 69.0, 69.0, 69.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 56.0, 55.0, 57.0, 56.0, 55.0, 55.0, 58.0], "value": 55.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 91.0, 84.0, 83.0, 85.0, 94.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 141.0, 136.0, 139.0, 152.0, 147.0, 140.0, 137.0, 184.0, 138.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 194.0, 197.0, 196.0, 189.0, 207.0, 211.0, 197.0, 200.0, 199.0], "value": 197.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 72.0, 73.0, 73.0, 73.0, 72.0, 71.0, 72.0, 75.0], "value": 72.7, "unit": "score"}]}]} 05:31:06 INFO - Return code: 0 05:31:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:31:06 ERROR - # TBPL SUCCESS # 05:31:06 INFO - Running post-action listener: _resource_record_post_action 05:31:06 INFO - [mozharness: 2017-03-28 12:31:06.661059Z] Finished run-tests step (success) 05:31:06 INFO - Running post-run listener: _resource_record_post_run 05:31:06 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 372637696; Read time: 72; Write time: 264912 05:31:06 INFO - TinderboxPrint: CPU usage
13.3% 05:31:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 72 05:31:06 INFO - TinderboxPrint: I/O write bytes / time
372,637,696 / 264,912 05:31:06 INFO - TinderboxPrint: CPU idle
8,319.5 (86.7%) 05:31:06 INFO - TinderboxPrint: CPU user
1,221.8 (12.7%) 05:31:06 INFO - TinderboxPrint: Swap in / out
0 / 0 05:31:06 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113479680; Read time: 72; Write time: 120944 05:31:06 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 258621440; Read time: 0; Write time: 143632 05:31:07 INFO - Running post-run listener: _upload_blobber_files 05:31:07 INFO - Blob upload gear active. 05:31:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:07 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 05:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:31:07 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:31:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:31:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:08 INFO - (blobuploader) - INFO - Done attempting. 05:31:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:31:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:31:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:09 INFO - (blobuploader) - INFO - Done attempting. 05:31:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:31:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:31:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:10 INFO - (blobuploader) - INFO - Done attempting. 05:31:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:31:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:31:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:13 INFO - (blobuploader) - INFO - Done attempting. 05:31:13 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:13 INFO - Return code: 0 05:31:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:13 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e802172f4c48f4959edab929b8b8800c58a638aff26f6cb1a1510173bdf4eed2025c1d0953a9081cc02aa67b59b9051643c39c1cef859d923e3a390d766d5670", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f595773311cd18523f2c4c1719cbb62bbab27ae23ad11427266abd73eafe7cbddb95922a97ef58d2830ae124eb3632fb84de80af199a504baa880d31034ccc8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54a9691bb2ab2a1bcd99aedd2ab1c55a3c22d16ae00f264788616c4135059c93199eb0abc7a43b5b2023612a31cee6f168c63dfa9d2ac62797950e9081d75660", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ddc7af3f6e8ed436a8c923fcc60f23318f7e21b40e2edad4095184d44a3be7c284a03b3abf296f08265a855c47e7805c0ed8ad008adca1095218511cbef0d04f"} 05:31:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:13 INFO - Contents: 05:31:13 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e802172f4c48f4959edab929b8b8800c58a638aff26f6cb1a1510173bdf4eed2025c1d0953a9081cc02aa67b59b9051643c39c1cef859d923e3a390d766d5670", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f595773311cd18523f2c4c1719cbb62bbab27ae23ad11427266abd73eafe7cbddb95922a97ef58d2830ae124eb3632fb84de80af199a504baa880d31034ccc8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54a9691bb2ab2a1bcd99aedd2ab1c55a3c22d16ae00f264788616c4135059c93199eb0abc7a43b5b2023612a31cee6f168c63dfa9d2ac62797950e9081d75660", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ddc7af3f6e8ed436a8c923fcc60f23318f7e21b40e2edad4095184d44a3be7c284a03b3abf296f08265a855c47e7805c0ed8ad008adca1095218511cbef0d04f"} 05:31:13 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:13 INFO - Copying logs to upload dir... 05:31:13 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.162608 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-28 05:31:13.208950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:31:13.212835) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e802172f4c48f4959edab929b8b8800c58a638aff26f6cb1a1510173bdf4eed2025c1d0953a9081cc02aa67b59b9051643c39c1cef859d923e3a390d766d5670", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f595773311cd18523f2c4c1719cbb62bbab27ae23ad11427266abd73eafe7cbddb95922a97ef58d2830ae124eb3632fb84de80af199a504baa880d31034ccc8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54a9691bb2ab2a1bcd99aedd2ab1c55a3c22d16ae00f264788616c4135059c93199eb0abc7a43b5b2023612a31cee6f168c63dfa9d2ac62797950e9081d75660", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ddc7af3f6e8ed436a8c923fcc60f23318f7e21b40e2edad4095184d44a3be7c284a03b3abf296f08265a855c47e7805c0ed8ad008adca1095218511cbef0d04f"} build_url:https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011162 build_url: 'https://queue.taskcluster.net/v1/task/bsyIprExTAeKjTbpenp6iA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e802172f4c48f4959edab929b8b8800c58a638aff26f6cb1a1510173bdf4eed2025c1d0953a9081cc02aa67b59b9051643c39c1cef859d923e3a390d766d5670", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f595773311cd18523f2c4c1719cbb62bbab27ae23ad11427266abd73eafe7cbddb95922a97ef58d2830ae124eb3632fb84de80af199a504baa880d31034ccc8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54a9691bb2ab2a1bcd99aedd2ab1c55a3c22d16ae00f264788616c4135059c93199eb0abc7a43b5b2023612a31cee6f168c63dfa9d2ac62797950e9081d75660", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ddc7af3f6e8ed436a8c923fcc60f23318f7e21b40e2edad4095184d44a3be7c284a03b3abf296f08265a855c47e7805c0ed8ad008adca1095218511cbef0d04f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:31:13.244330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:31:13.244873) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1490703009.880181-172476631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004407 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:31:13.329299) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:31:13.329619) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:31:13.330091) ========= ========= Total master_lag: 0.19 =========