builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1469150294.44 results: success (0) buildid: 20160721163009 builduid: 7c37a33bc9954f66814f145af95d0fd1 revision: 2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.438421) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.438905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.439226) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.456100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.456639) ========= 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=523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 18:18:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 16.5M=0.001s 2016-07-21 18:18:15 (16.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.586158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.586503) ========= 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=523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021936 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.650400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:14.650748) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8 --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=523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 18:18:15,132 truncating revision to first 12 chars 2016-07-21 18:18:15,132 Setting DEBUG logging. 2016-07-21 18:18:15,132 attempt 1/10 2016-07-21 18:18:15,132 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2e3390571fdb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 18:18:15,447 unpacking tar archive at: mozilla-central-2e3390571fdb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:15.315604) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:15.315984) ========= script_repo_revision: 2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:15.316434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:15.316750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:15.325760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-21 18:18:15.326078) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:18:15 INFO - MultiFileLogger online at 20160721 18:18:15 in /builds/slave/test 18:18:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:18:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:18:15 INFO - {'append_to_log': False, 18:18:15 INFO - 'base_work_dir': '/builds/slave/test', 18:18:15 INFO - 'blob_upload_branch': 'Firefox', 18:18:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:18:15 INFO - 'branch': 'Firefox', 18:18:15 INFO - 'buildbot_json_path': 'buildprops.json', 18:18:15 INFO - 'config_files': ('talos/linux_config.py',), 18:18:15 INFO - 'default_actions': ('clobber', 18:18:15 INFO - 'read-buildbot-config', 18:18:15 INFO - 'download-and-extract', 18:18:15 INFO - 'populate-webroot', 18:18:15 INFO - 'create-virtualenv', 18:18:15 INFO - 'install', 18:18:15 INFO - 'run-tests'), 18:18:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:18:15 INFO - 'download_minidump_stackwalk': True, 18:18:15 INFO - 'download_symbols': 'ondemand', 18:18:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:18:15 INFO - 'tooltool.py': '/tools/tooltool.py', 18:18:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:18:15 INFO - '/tools/misc-python/virtualenv.py')}, 18:18:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:18:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:18:15 INFO - 'installer_path': 'installer.exe', 18:18:15 INFO - 'log_level': 'info', 18:18:15 INFO - 'log_name': 'talos', 18:18:15 INFO - 'log_to_console': True, 18:18:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:18:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:18:15 INFO - 'opt_config_files': (), 18:18:15 INFO - 'pip_index': False, 18:18:15 INFO - 'sps_profile': False, 18:18:15 INFO - 'sps_profile_interval': 0, 18:18:15 INFO - 'suite': 'dromaeojs', 18:18:15 INFO - 'system_bits': '32', 18:18:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:18:15 INFO - 'title': 'talos-linux64-ix-065', 18:18:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:18:15 INFO - 'use_talos_json': True, 18:18:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:18:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:18:15 INFO - 'work_dir': 'build'} 18:18:15 INFO - [mozharness: 2016-07-22 01:18:15.898356Z] Running clobber step. 18:18:15 INFO - Running pre-action listener: _resource_record_pre_action 18:18:15 INFO - Running main action method: clobber 18:18:15 INFO - rmtree: /builds/slave/test/build 18:18:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:18:16 INFO - Running post-action listener: _resource_record_post_action 18:18:16 INFO - [mozharness: 2016-07-22 01:18:16.873991Z] Finished clobber step (success) 18:18:16 INFO - [mozharness: 2016-07-22 01:18:16.874093Z] Running read-buildbot-config step. 18:18:16 INFO - Running pre-action listener: _resource_record_pre_action 18:18:16 INFO - Running main action method: read_buildbot_config 18:18:16 INFO - Using buildbot properties: 18:18:16 INFO - { 18:18:16 INFO - "project": "", 18:18:16 INFO - "product": "firefox", 18:18:16 INFO - "script_repo_revision": "production", 18:18:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:18:16 INFO - "repository": "", 18:18:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:18:16 INFO - "buildid": "20160721163009", 18:18:16 INFO - "pgo_build": "True", 18:18:16 INFO - "basedir": "/builds/slave/test", 18:18:16 INFO - "buildnumber": 508, 18:18:16 INFO - "slavename": "talos-linux64-ix-065", 18:18:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:18:16 INFO - "platform": "ubuntu64_hw", 18:18:16 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:18:16 INFO - "branch": "mozilla-central", 18:18:16 INFO - "repo_path": "mozilla-central", 18:18:16 INFO - "revision": "2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8", 18:18:16 INFO - "stage_platform": "linux64-pgo", 18:18:16 INFO - "builduid": "7c37a33bc9954f66814f145af95d0fd1", 18:18:16 INFO - "slavebuilddir": "test" 18:18:16 INFO - } 18:18:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:18:16 INFO - Running post-action listener: _resource_record_post_action 18:18:16 INFO - [mozharness: 2016-07-22 01:18:16.876547Z] Finished read-buildbot-config step (success) 18:18:16 INFO - [mozharness: 2016-07-22 01:18:16.876637Z] Running download-and-extract step. 18:18:16 INFO - Running pre-action listener: _resource_record_pre_action 18:18:16 INFO - Running main action method: download_and_extract 18:18:16 INFO - mkdir: /builds/slave/test/build/tests 18:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:16 INFO - https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:18:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:18:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:18:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:18:19 INFO - Downloaded 1361 bytes. 18:18:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:18:19 INFO - Using the following test package requirements: 18:18:19 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:18:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:18:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:18:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'jsshell-linux-x86_64.zip'], 18:18:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:18:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:18:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:18:19 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:18:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:18:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:18:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:18:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:19 INFO - https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:18:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:18:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:18:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:18:21 INFO - Downloaded 24306748 bytes. 18:18:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:18:22 INFO - Return code: 0 18:18:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:18:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:22 INFO - https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:18:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:18:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:18:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:18:25 INFO - Downloaded 24306748 bytes. 18:18:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:18:27 INFO - Return code: 0 18:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:27 INFO - https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:18:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:18:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:18:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:18:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:18:28 INFO - Downloaded 14403317 bytes. 18:18:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:18:28 INFO - Return code: 0 18:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:28 INFO - https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:18:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:18:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:18:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:18:30 INFO - Downloaded 60870021 bytes. 18:18:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:18:30 INFO - mkdir: /builds/slave/test/properties 18:18:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:30 INFO - Contents: 18:18:30 INFO - build_url:https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:18:31 INFO - Running post-action listener: _resource_record_post_action 18:18:31 INFO - Running post-action listener: set_extra_try_arguments 18:18:31 INFO - [mozharness: 2016-07-22 01:18:31.428521Z] Finished download-and-extract step (success) 18:18:31 INFO - [mozharness: 2016-07-22 01:18:31.428852Z] Running populate-webroot step. 18:18:31 INFO - Running pre-action listener: _resource_record_pre_action 18:18:31 INFO - Running main action method: populate_webroot 18:18:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:31 INFO - '--tppagecycles', 18:18:31 INFO - '7'], 18:18:31 INFO - 'tests': ['tsvgm']}}, 18:18:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['damp', 'tps']}, 18:18:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'tests': ['damp', 'tps']}, 18:18:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['dromaeo_dom']}, 18:18:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['basic_compositor_video']}, 18:18:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:18:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tsvgx', 18:18:31 INFO - 'tsvgr_opacity', 18:18:31 INFO - 'tart', 18:18:31 INFO - 'tscrollx', 18:18:31 INFO - 'cart']}, 18:18:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:31 INFO - 'tsvgr_opacity', 18:18:31 INFO - 'tart', 18:18:31 INFO - 'tscrollx', 18:18:31 INFO - 'cart']}, 18:18:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tp5o']}, 18:18:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'tests': ['tp5o']}, 18:18:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s', 18:18:31 INFO - '--xperf_path', 18:18:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:31 INFO - 'tests': ['tp5n']}, 18:18:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--xperf_path', 18:18:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:31 INFO - 'tests': ['tp5n']}}, 18:18:31 INFO - 'talos.zip': {'path': '', 18:18:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:31 INFO - Running post-action listener: _resource_record_post_action 18:18:31 INFO - [mozharness: 2016-07-22 01:18:31.445711Z] Finished populate-webroot step (success) 18:18:31 INFO - [mozharness: 2016-07-22 01:18:31.445801Z] Running create-virtualenv step. 18:18:31 INFO - Running pre-action listener: _resource_record_pre_action 18:18:31 INFO - Running main action method: create_virtualenv 18:18:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:31 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 18:18:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:31 INFO - Using real prefix '/usr' 18:18:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:34 INFO - Installing pip.................done. 18:18:34 INFO - Return code: 0 18:18:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:34 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:34 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 18:18:34 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 18:18:34 INFO - Using env: {'DISPLAY': ':0', 18:18:34 INFO - 'HOME': '/home/cltbld', 18:18:34 INFO - 'LANG': 'en_US.UTF-8', 18:18:34 INFO - 'LANGUAGE': 'en_US:en', 18:18:34 INFO - 'LOGNAME': 'cltbld', 18:18:34 INFO - 'MAIL': '/var/mail/cltbld', 18:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:34 INFO - 'MOZ_NO_REMOTE': '1', 18:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:34 INFO - 'NO_EM_RESTART': '1', 18:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:34 INFO - 'PWD': '/builds/slave/test', 18:18:34 INFO - 'SHELL': '/bin/bash', 18:18:34 INFO - 'SHLVL': '1', 18:18:34 INFO - 'TERM': 'linux', 18:18:34 INFO - 'TMOUT': '86400', 18:18:34 INFO - 'USER': 'cltbld', 18:18:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:34 INFO - '_': '/tools/buildbot/bin/python'} 18:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:34 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 18:18:34 INFO - Cleaning up... 18:18:34 INFO - Return code: 0 18:18:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:18:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:34 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:34 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 18:18:34 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 18:18:34 INFO - Using env: {'DISPLAY': ':0', 18:18:34 INFO - 'HOME': '/home/cltbld', 18:18:34 INFO - 'LANG': 'en_US.UTF-8', 18:18:34 INFO - 'LANGUAGE': 'en_US:en', 18:18:34 INFO - 'LOGNAME': 'cltbld', 18:18:34 INFO - 'MAIL': '/var/mail/cltbld', 18:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:34 INFO - 'MOZ_NO_REMOTE': '1', 18:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:34 INFO - 'NO_EM_RESTART': '1', 18:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:34 INFO - 'PWD': '/builds/slave/test', 18:18:34 INFO - 'SHELL': '/bin/bash', 18:18:34 INFO - 'SHLVL': '1', 18:18:34 INFO - 'TERM': 'linux', 18:18:34 INFO - 'TMOUT': '86400', 18:18:34 INFO - 'USER': 'cltbld', 18:18:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:34 INFO - '_': '/tools/buildbot/bin/python'} 18:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:34 INFO - Downloading/unpacking psutil>=3.1.1 18:18:34 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 18:18:34 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 18:18:34 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 18:18:34 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 18:18:34 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 18:18:34 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 18:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:36 INFO - Installing collected packages: psutil 18:18:36 INFO - Running setup.py install for psutil 18:18:36 INFO - building 'psutil._psutil_linux' extension 18:18:36 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 18:18:36 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 18:18:36 INFO - building 'psutil._psutil_posix' extension 18:18:36 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 18:18:36 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 18:18:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:36 INFO - Successfully installed psutil 18:18:36 INFO - Cleaning up... 18:18:37 INFO - Return code: 0 18:18:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:37 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:37 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 18:18:37 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 18:18:37 INFO - Using env: {'DISPLAY': ':0', 18:18:37 INFO - 'HOME': '/home/cltbld', 18:18:37 INFO - 'LANG': 'en_US.UTF-8', 18:18:37 INFO - 'LANGUAGE': 'en_US:en', 18:18:37 INFO - 'LOGNAME': 'cltbld', 18:18:37 INFO - 'MAIL': '/var/mail/cltbld', 18:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:37 INFO - 'MOZ_NO_REMOTE': '1', 18:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:37 INFO - 'NO_EM_RESTART': '1', 18:18:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:37 INFO - 'PWD': '/builds/slave/test', 18:18:37 INFO - 'SHELL': '/bin/bash', 18:18:37 INFO - 'SHLVL': '1', 18:18:37 INFO - 'TERM': 'linux', 18:18:37 INFO - 'TMOUT': '86400', 18:18:37 INFO - 'USER': 'cltbld', 18:18:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:37 INFO - '_': '/tools/buildbot/bin/python'} 18:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18:37 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 18:18:37 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 18:18:37 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 18:18:37 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 18:18:37 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 18:18:37 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 18:18:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:39 INFO - Installing collected packages: mozsystemmonitor 18:18:39 INFO - Running setup.py install for mozsystemmonitor 18:18:39 INFO - Successfully installed mozsystemmonitor 18:18:39 INFO - Cleaning up... 18:18:39 INFO - Return code: 0 18:18:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:39 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:39 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 18:18:39 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 18:18:39 INFO - Using env: {'DISPLAY': ':0', 18:18:39 INFO - 'HOME': '/home/cltbld', 18:18:39 INFO - 'LANG': 'en_US.UTF-8', 18:18:39 INFO - 'LANGUAGE': 'en_US:en', 18:18:39 INFO - 'LOGNAME': 'cltbld', 18:18:39 INFO - 'MAIL': '/var/mail/cltbld', 18:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:39 INFO - 'MOZ_NO_REMOTE': '1', 18:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:39 INFO - 'NO_EM_RESTART': '1', 18:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:39 INFO - 'PWD': '/builds/slave/test', 18:18:39 INFO - 'SHELL': '/bin/bash', 18:18:39 INFO - 'SHLVL': '1', 18:18:39 INFO - 'TERM': 'linux', 18:18:39 INFO - 'TMOUT': '86400', 18:18:39 INFO - 'USER': 'cltbld', 18:18:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:39 INFO - '_': '/tools/buildbot/bin/python'} 18:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:39 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:41 INFO - warning: no files found matching '*.txt' 18:18:41 INFO - no previously-included directories found matching 'build' 18:18:41 INFO - no previously-included directories found matching 'dist' 18:18:41 INFO - no previously-included directories found matching '.git*' 18:18:41 INFO - Installing collected packages: jsonschema, functools32 18:18:41 INFO - Running setup.py install for functools32 18:18:41 INFO - warning: no files found matching '*.txt' 18:18:41 INFO - no previously-included directories found matching 'build' 18:18:41 INFO - no previously-included directories found matching 'dist' 18:18:41 INFO - no previously-included directories found matching '.git*' 18:18:41 INFO - Successfully installed jsonschema functools32 18:18:41 INFO - Cleaning up... 18:18:41 INFO - Return code: 0 18:18:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:41 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:41 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 18:18:41 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 18:18:41 INFO - Using env: {'DISPLAY': ':0', 18:18:41 INFO - 'HOME': '/home/cltbld', 18:18:41 INFO - 'LANG': 'en_US.UTF-8', 18:18:41 INFO - 'LANGUAGE': 'en_US:en', 18:18:41 INFO - 'LOGNAME': 'cltbld', 18:18:41 INFO - 'MAIL': '/var/mail/cltbld', 18:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:41 INFO - 'MOZ_NO_REMOTE': '1', 18:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:41 INFO - 'NO_EM_RESTART': '1', 18:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:41 INFO - 'PWD': '/builds/slave/test', 18:18:41 INFO - 'SHELL': '/bin/bash', 18:18:41 INFO - 'SHLVL': '1', 18:18:41 INFO - 'TERM': 'linux', 18:18:41 INFO - 'TMOUT': '86400', 18:18:41 INFO - 'USER': 'cltbld', 18:18:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:41 INFO - '_': '/tools/buildbot/bin/python'} 18:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:42 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:42 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 18:18:42 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 18:18:42 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 18:18:42 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 18:18:42 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 18:18:42 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 18:18:44 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - Downloading docopt-0.6.1.tar.gz 18:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:44 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:44 INFO - Running setup.py install for blobuploader 18:18:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:44 INFO - Running setup.py install for requests 18:18:45 INFO - Running setup.py install for docopt 18:18:45 INFO - Successfully installed blobuploader requests docopt 18:18:45 INFO - Cleaning up... 18:18:45 INFO - Return code: 0 18:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:45 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 18:18:45 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 18:18:45 INFO - Using env: {'DISPLAY': ':0', 18:18:45 INFO - 'HOME': '/home/cltbld', 18:18:45 INFO - 'LANG': 'en_US.UTF-8', 18:18:45 INFO - 'LANGUAGE': 'en_US:en', 18:18:45 INFO - 'LOGNAME': 'cltbld', 18:18:45 INFO - 'MAIL': '/var/mail/cltbld', 18:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:45 INFO - 'MOZ_NO_REMOTE': '1', 18:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:45 INFO - 'NO_EM_RESTART': '1', 18:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:45 INFO - 'PWD': '/builds/slave/test', 18:18:45 INFO - 'SHELL': '/bin/bash', 18:18:45 INFO - 'SHLVL': '1', 18:18:45 INFO - 'TERM': 'linux', 18:18:45 INFO - 'TMOUT': '86400', 18:18:45 INFO - 'USER': 'cltbld', 18:18:45 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:45 INFO - '_': '/tools/buildbot/bin/python'} 18:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:45 INFO - Running setup.py (path:/tmp/pip-PJFTM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:45 INFO - Running setup.py (path:/tmp/pip-qNxG3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:45 INFO - Running setup.py (path:/tmp/pip-uEbjVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:45 INFO - Running setup.py (path:/tmp/pip-Pi5DPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:45 INFO - Running setup.py (path:/tmp/pip-AKjNF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:46 INFO - Running setup.py (path:/tmp/pip-9TkCxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:46 INFO - Running setup.py (path:/tmp/pip-6Aw9PN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:46 INFO - Running setup.py (path:/tmp/pip-CN7Qmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:46 INFO - Running setup.py (path:/tmp/pip-XXQbtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:46 INFO - Running setup.py (path:/tmp/pip-P1Qy3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:46 INFO - Running setup.py (path:/tmp/pip-3OyNyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:46 INFO - Running setup.py (path:/tmp/pip-6y2VjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:46 INFO - Running setup.py (path:/tmp/pip-GS_1UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:46 INFO - Running setup.py (path:/tmp/pip-I5DYBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:46 INFO - Running setup.py (path:/tmp/pip-9T63IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:46 INFO - Running setup.py (path:/tmp/pip-ww6j8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:46 INFO - Running setup.py (path:/tmp/pip-33kizf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:46 INFO - Running setup.py install for manifestparser 18:18:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:46 INFO - Running setup.py install for mozcrash 18:18:47 INFO - Running setup.py install for mozdebug 18:18:47 INFO - Running setup.py install for mozdevice 18:18:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozfile 18:18:47 INFO - Running setup.py install for mozhttpd 18:18:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozinfo 18:18:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozInstall 18:18:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozleak 18:18:47 INFO - Running setup.py install for mozlog 18:18:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for moznetwork 18:18:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozprocess 18:18:48 INFO - Running setup.py install for mozprofile 18:18:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozrunner 18:18:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozscreenshot 18:18:48 INFO - Running setup.py install for moztest 18:18:48 INFO - Running setup.py install for mozversion 18:18:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:48 INFO - Cleaning up... 18:18:48 INFO - Return code: 0 18:18:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:48 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:48 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 18:18:48 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 18:18:48 INFO - Using env: {'DISPLAY': ':0', 18:18:48 INFO - 'HOME': '/home/cltbld', 18:18:48 INFO - 'LANG': 'en_US.UTF-8', 18:18:48 INFO - 'LANGUAGE': 'en_US:en', 18:18:48 INFO - 'LOGNAME': 'cltbld', 18:18:48 INFO - 'MAIL': '/var/mail/cltbld', 18:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:48 INFO - 'MOZ_NO_REMOTE': '1', 18:18:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:48 INFO - 'NO_EM_RESTART': '1', 18:18:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:48 INFO - 'PWD': '/builds/slave/test', 18:18:48 INFO - 'SHELL': '/bin/bash', 18:18:48 INFO - 'SHLVL': '1', 18:18:48 INFO - 'TERM': 'linux', 18:18:48 INFO - 'TMOUT': '86400', 18:18:48 INFO - 'USER': 'cltbld', 18:18:48 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:48 INFO - '_': '/tools/buildbot/bin/python'} 18:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:48 INFO - Running setup.py (path:/tmp/pip-X5GoRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:48 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)) 18:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:48 INFO - Running setup.py (path:/tmp/pip-aPwzbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:49 INFO - Running setup.py (path:/tmp/pip-IDxGnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:49 INFO - Running setup.py (path:/tmp/pip-UdCWPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:49 INFO - Running setup.py (path:/tmp/pip-Z_yCDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:49 INFO - Running setup.py (path:/tmp/pip-vfMX1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:49 INFO - Running setup.py (path:/tmp/pip-e3baum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:49 INFO - Running setup.py (path:/tmp/pip-8SW3MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:49 INFO - Running setup.py (path:/tmp/pip-VpdrTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:49 INFO - Running setup.py (path:/tmp/pip-69ZNsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:49 INFO - Running setup.py (path:/tmp/pip-Psr7rJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:49 INFO - Running setup.py (path:/tmp/pip-Ij02v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:49 INFO - Running setup.py (path:/tmp/pip-aJoFUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:49 INFO - Running setup.py (path:/tmp/pip-FLnvR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:49 INFO - Running setup.py (path:/tmp/pip-gFZNGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:50 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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:50 INFO - Running setup.py (path:/tmp/pip-bHpPfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:50 INFO - Running setup.py (path:/tmp/pip-VgOtfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:50 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)) 18:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:18:50 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)) 18:18:50 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:18:50 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:18:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:52 INFO - Downloading blessings-1.6.tar.gz 18:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:52 INFO - Installing collected packages: blessings 18:18:52 INFO - Running setup.py install for blessings 18:18:52 INFO - Successfully installed blessings 18:18:52 INFO - Cleaning up... 18:18:52 INFO - Return code: 0 18:18:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:52 INFO - Reading from file tmpfile_stdout 18:18:52 INFO - Current package versions: 18:18:52 INFO - argparse == 1.2.1 18:18:52 INFO - blessings == 1.6 18:18:52 INFO - blobuploader == 1.2.4 18:18:52 INFO - docopt == 0.6.1 18:18:52 INFO - functools32 == 3.2.3-2 18:18:52 INFO - jsonschema == 2.5.1 18:18:52 INFO - manifestparser == 1.1 18:18:52 INFO - mozInstall == 1.12 18:18:52 INFO - mozcrash == 0.17 18:18:52 INFO - mozdebug == 0.1 18:18:52 INFO - mozdevice == 0.48 18:18:52 INFO - mozfile == 1.2 18:18:52 INFO - mozhttpd == 0.7 18:18:52 INFO - mozinfo == 0.9 18:18:52 INFO - mozleak == 0.1 18:18:52 INFO - mozlog == 3.3 18:18:52 INFO - moznetwork == 0.27 18:18:52 INFO - mozprocess == 0.23 18:18:52 INFO - mozprofile == 0.28 18:18:52 INFO - mozrunner == 6.12 18:18:52 INFO - mozscreenshot == 0.1 18:18:52 INFO - mozsystemmonitor == 0.3 18:18:52 INFO - moztest == 0.7 18:18:52 INFO - mozversion == 1.4 18:18:52 INFO - psutil == 3.1.1 18:18:52 INFO - requests == 1.2.3 18:18:52 INFO - wsgiref == 0.1.2 18:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:52 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 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:52 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 18:18:52 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 18:18:52 INFO - Using env: {'DISPLAY': ':0', 18:18:52 INFO - 'HOME': '/home/cltbld', 18:18:52 INFO - 'LANG': 'en_US.UTF-8', 18:18:52 INFO - 'LANGUAGE': 'en_US:en', 18:18:52 INFO - 'LOGNAME': 'cltbld', 18:18:52 INFO - 'MAIL': '/var/mail/cltbld', 18:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:52 INFO - 'MOZ_NO_REMOTE': '1', 18:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:52 INFO - 'NO_EM_RESTART': '1', 18:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:52 INFO - 'PWD': '/builds/slave/test', 18:18:52 INFO - 'SHELL': '/bin/bash', 18:18:52 INFO - 'SHLVL': '1', 18:18:52 INFO - 'TERM': 'linux', 18:18:52 INFO - 'TMOUT': '86400', 18:18:52 INFO - 'USER': 'cltbld', 18:18:52 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:52 INFO - '_': '/tools/buildbot/bin/python'} 18:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 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)) 18:18:52 INFO - Cleaning up... 18:18:52 INFO - Return code: 0 18:18:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2397180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, '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': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', '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 18:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:18:52 INFO - Using env: {'DISPLAY': ':0', 18:18:52 INFO - 'HOME': '/home/cltbld', 18:18:52 INFO - 'LANG': 'en_US.UTF-8', 18:18:52 INFO - 'LANGUAGE': 'en_US:en', 18:18:52 INFO - 'LOGNAME': 'cltbld', 18:18:52 INFO - 'MAIL': '/var/mail/cltbld', 18:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:52 INFO - 'MOZ_NO_REMOTE': '1', 18:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:52 INFO - 'NO_EM_RESTART': '1', 18:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:52 INFO - 'PWD': '/builds/slave/test', 18:18:52 INFO - 'SHELL': '/bin/bash', 18:18:52 INFO - 'SHLVL': '1', 18:18:52 INFO - 'TERM': 'linux', 18:18:52 INFO - 'TMOUT': '86400', 18:18:52 INFO - 'USER': 'cltbld', 18:18:52 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:52 INFO - '_': '/tools/buildbot/bin/python'} 18:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:18:53 INFO - Cleaning up... 18:18:53 INFO - Return code: 0 18:18:53 INFO - Running post-action listener: _resource_record_post_action 18:18:53 INFO - Running post-action listener: _start_resource_monitoring 18:18:53 INFO - Starting resource monitoring. 18:18:53 INFO - [mozharness: 2016-07-22 01:18:53.089429Z] Finished create-virtualenv step (success) 18:18:53 INFO - [mozharness: 2016-07-22 01:18:53.089900Z] Running install step. 18:18:53 INFO - Running pre-action listener: _resource_record_pre_action 18:18:53 INFO - Running main action method: install 18:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:53 INFO - Reading from file tmpfile_stdout 18:18:53 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:53 INFO - Reading from file tmpfile_stdout 18:18:53 INFO - Output received: 18:18:53 INFO - Usage: mozinstall [options] installer 18:18:53 INFO - Options: 18:18:53 INFO - -h, --help show this help message and exit 18:18:53 INFO - -d DEST, --destination=DEST 18:18:53 INFO - Directory to install application into. [default: 18:18:53 INFO - "/builds/slave/test"] 18:18:53 INFO - --app=APP Application being installed. [default: firefox] 18:18:53 INFO - mkdir: /builds/slave/test/build/application 18:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:19:10 INFO - Reading from file tmpfile_stdout 18:19:10 INFO - Output received: 18:19:10 INFO - /builds/slave/test/build/application/firefox/firefox 18:19:10 INFO - Running post-action listener: _resource_record_post_action 18:19:10 INFO - [mozharness: 2016-07-22 01:19:10.622696Z] Finished install step (success) 18:19:10 INFO - [mozharness: 2016-07-22 01:19:10.623035Z] Running run-tests step. 18:19:10 INFO - Running pre-action listener: _resource_record_pre_action 18:19:10 INFO - Running main action method: run_tests 18:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:19:10 INFO - Python 2.7.3 18:19:10 INFO - Return code: 0 18:19:10 INFO - grabbing minidump binary from tooltool 18:19:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:10 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 0x23953b0>, '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 0x23962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:19:10 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 18:19:10 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 18:19:10 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 18:19:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:19:10 INFO - Return code: 0 18:19:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:19:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:19:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:19:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:19:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:19:10 INFO - Using env: {'DISPLAY': ':0', 18:19:10 INFO - 'HOME': '/home/cltbld', 18:19:10 INFO - 'LANG': 'en_US.UTF-8', 18:19:10 INFO - 'LANGUAGE': 'en_US:en', 18:19:10 INFO - 'LOGNAME': 'cltbld', 18:19:10 INFO - 'MAIL': '/var/mail/cltbld', 18:19:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:19:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:10 INFO - 'MOZ_NO_REMOTE': '1', 18:19:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:19:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:10 INFO - 'NO_EM_RESTART': '1', 18:19:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:10 INFO - 'PWD': '/builds/slave/test', 18:19:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:19:10 INFO - 'SHELL': '/bin/bash', 18:19:10 INFO - 'SHLVL': '1', 18:19:10 INFO - 'TERM': 'linux', 18:19:10 INFO - 'TMOUT': '86400', 18:19:10 INFO - 'USER': 'cltbld', 18:19:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634', 18:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:10 INFO - '_': '/tools/buildbot/bin/python'} 18:19:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:19:10 INFO - mozversion application_buildid: 20160721163009 18:19:10 INFO - mozversion application_changeset: 2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8 18:19:10 INFO - mozversion application_display_name: Nightly 18:19:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:19:10 INFO - mozversion application_name: Firefox 18:19:10 INFO - mozversion application_remotingname: firefox 18:19:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:19:10 INFO - mozversion application_vendor: Mozilla 18:19:10 INFO - mozversion application_version: 50.0a1 18:19:10 INFO - mozversion platform_buildid: 20160721163009 18:19:10 INFO - mozversion platform_changeset: 2e3390571fdb3a1ff3d2f7f828adf67dbc237bc8 18:19:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:19:10 INFO - mozversion platform_version: 50.0a1 18:19:10 INFO - using testdate: 1469150350 18:19:10 INFO - actual date: 1469150350 18:19:10 INFO - starting webserver on 'localhost:34365' 18:19:10 INFO - SUITE-START | Running 2 tests 18:19:10 INFO - TEST-START | dromaeo_css 18:19:10 INFO - operating with platform_type : linux_ 18:19:10 INFO - Initialising browser for dromaeo_css test... 18:19:10 INFO - TEST-INFO | started process 7234 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsXGvSY/profile http://localhost:34365/getInfo.html) 18:19:13 INFO - PROCESS | 7234 | JavaScript error: , line 0: uncaught exception: undefined 18:19:14 INFO - PROCESS | 7234 | __metrics Screen width/height:1600/1200 18:19:14 INFO - PROCESS | 7234 | colorDepth:24 18:19:14 INFO - PROCESS | 7234 | Browser inner width/height: 1024/697 18:19:14 INFO - PROCESS | 7234 | __metrics 18:19:15 INFO - PROCESS | 7234 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:19:15 INFO - PROCESS | 7234 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:19:15 INFO - PROCESS | 7234 | console.error: PushService: 18:19:15 INFO - PROCESS | 7234 | stateChangeProcessEnqueue: Error transitioning state 18:19:15 INFO - PROCESS | 7234 | UnknownError 18:19:16 INFO - TEST-INFO | 7234: exit 0 18:19:16 INFO - Browser initialized. 18:19:16 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:16 INFO - TEST-INFO | started process 7313 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsXGvSY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:28 INFO - PROCESS | 7313 | RSS: Main: 158375936 18:19:28 INFO - PROCESS | 7313 | 18:20:19 INFO - PROCESS | 7313 | 1469150419904 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:22:27 INFO - PROCESS | 7313 | Cycle 1(1): loaded http://localhost:34365/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34365/tests/dromaeo/cssquery-ext.html) 18:22:28 INFO - PROCESS | 7313 | RSS: Main: 247705600 18:22:28 INFO - PROCESS | 7313 | 18:25:31 INFO - PROCESS | 7313 | Cycle 1(1): loaded http://localhost:34365/tests/dromaeo/cssquery-ext.html (next: http://localhost:34365/tests/dromaeo/cssquery-jquery.html) 18:25:31 INFO - PROCESS | 7313 | RSS: Main: 249593856 18:25:31 INFO - PROCESS | 7313 | 18:28:33 INFO - PROCESS | 7313 | Cycle 1(1): loaded http://localhost:34365/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34365/tests/dromaeo/cssquery-mootools.html) 18:28:34 INFO - PROCESS | 7313 | RSS: Main: 190550016 18:28:34 INFO - PROCESS | 7313 | 18:31:36 INFO - PROCESS | 7313 | Cycle 1(1): loaded http://localhost:34365/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34365/tests/dromaeo/cssquery-prototype.html) 18:31:37 INFO - PROCESS | 7313 | RSS: Main: 226099200 18:31:37 INFO - PROCESS | 7313 | 18:34:39 INFO - PROCESS | 7313 | Cycle 1(1): loaded http://localhost:34365/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34365/tests/dromaeo/cssquery-yui.html) 18:34:40 INFO - PROCESS | 7313 | RSS: Main: 192372736 18:34:40 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | Cycle 1(1): loaded http://localhost:34365/tests/dromaeo/cssquery-yui.html (next: http://localhost:34365/tests/dromaeo/cssquery-dojo.html) 18:37:43 INFO - PROCESS | 7313 | RSS: Main: 279556096 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | __start_tp_report 18:37:43 INFO - PROCESS | 7313 | _x_x_mozilla_page_load 18:37:43 INFO - PROCESS | 7313 | _x_x_mozilla_page_load_details 18:37:43 INFO - PROCESS | 7313 | |i|pagename|runs| 18:37:43 INFO - PROCESS | 7313 | |0;dojo.html;3054.2211736007316;3075.3234288456533;3109.4403007458677;3110.0825256549315;3121.313311071565;5219.321488206538;5329.147336426173;5338.092524270874;5442.918356224654;5454.236406903032;5365.382980957182;5373.973130134354;5400.1629747389115;5446.128619420893;5503.6147469677135;4900.362952816129;4901.90196196075;4939.901201975958;4952.579030782388;5051.242313652963;4995.500449954993;5028.994201159764;5029.572486338648;5034.446210916814;5041.949580504184;4780.474147843717;4823.493533179036;4829.034193161347;4910.729909854975;4913.926291105637;4799.7360145192;4822.397200349957;4840.588549973221;4857.368542089541;4917.262410638397;4749.952500474986;4819.638527110481;4846.36997190363;4853.247746599282;4870.902581948377;5344.5724342052545;5374.355077390699;5393.460653934614;5402.756875940592;5412.4587541245955;5442.972785136049;5599.972000140014;5650.886982260332;5660.264165658438;5738.913916291259;5701.6864072476;5729.942700572982;5773.191753154561;5779.797707080274;5790.652560846363;5184.5593124584075;5392.622516423852;5398.4871437213005;5398.4871437213405;5405.4324295949145;5812.215350927655;5863.970680146657;5876.971529982322;5961.582689211714;5969.462748352669;5665.433456654301;5668.206451096849;5719;5736.971315143398;5738.139279108083;5590.273264475673;5773.855653608609;5819.912701309484;5831.096180092092;5834.095715164156;5602.075657516546;5644.491995720405;5644.8871022579315;5745.1669507921115;5746.02317606008;5684.976704193202;5701.458361455655;5706.144078388192;5726.169705392778;5768.798092066758;4293.635041021485;4315.8489452869;4333.631641310524;4351.086271882877;4373.409589705413;3783.754055986352;3799.088218827517;3862.729608927404;3908.081600823804;3927.548331941863;5397.730113494388;5397.892042159135;5438.510534051954;5449.727513624303;5484.451554844483;5801.3038435388025;5802.535797136219;5813.5349172066135;5815.651060936358;5857.297124345021;5265.736713164327;5353.9196912046355;5396.91904621431;5490.286262785812;5492.725363731797;4956.132676781549;4957.082939656175;4991.425986011655;4993.725345106054;5002.624803139779;5859.150423188614;5890.145928840294;5897.351291357947;5910.527157827363;5911.556633252523;5583.134614134816;5642.181883626769;5745.827625171251;5759.51044161251;5759.625624334405;5837.912431313619;5895.882082358243;5904.940950590439;5933.021051526623;5956.404359564009;5553.555715542828;5609.691466969356;5622.2128901954575;5623.409541998195;5627.915581266366;5522.30971128609;5733.283339582552;5739.5695322850115;5751.194832723506;5757.280339957505;5618.101103823532;5707.372189059117;5717.8570535736935;5749.223854779551;5773.740181691918;5658.264425624642;5694.288213973254;5703.771849125988;5708.743106560298;5710.086386178192;5405.918911216414;5499.285092937893;5650.6327088740745;5661.886762264649;5669.31968163823;5609.49514543693;5700.600957932905;5708.343540492897;5723.3418156912485;5731.684757338219;5529.889402212014;5530.972345138249;5538.224648549126;5543.445655434424;5545.279113715191 18:37:43 INFO - PROCESS | 7313 | |1;ext.html;608.1182285685755;629.7229219143577;631.07547442996;645.8256270806883;659.423004870738;31200;43117;43694;43732;46755.53244467554;44793;45109.323360149625;45202.32196517055;45223.77388113063;45245.54754452455;8843.911560884406;8994.640214391417;8996.955015224914;8997.820043599124;9010;8931.866022009675;8993;9017.774555636095;9018.41380310281;9019.639214431414;9057.184853363196;9097.590608422637;9105.408148470327;9105.408148470362;9112.179903808656;9006.819863602725;9022.729318120468;9042.367034307601;9072.228860546795;9077.773055673595;8884.111588841064;8953.328500362433;8955.865662015074;8966.19304262613;8972.685955991508;42960.35559466611;43292.13415731699;43301.78349108266;43372.132557348676;43392.56607433917;3065.4022465619214;3437.346904088215;3463.359278533454;3500.8949731508096;3508.7372010273853;33783.6621633783;33912.32175356504;34256.91687748115;35353.823230883936;35542.28915421703;3984.362501999666;4067.959320406788;4107;4257.638100761439;4340.782960851976;250834;252368;252568.99999999817;252768.99999999817;252853;58974;59539.70230148908;59571.404285957455;59611.40388596145;59745.701271493366;55308.72345638246;56536;56562.43437565654;59703;62309.376906230355;15777.211139442981;16140.838591614169;16207.270672819752;16693;16693.414125658044;13252.138610990254;13316.533921312901;13403.329833508285;13428.664283392993;13462;16633.334666613442;16858.40995565174;16915.915420423065;16921.40775072891;17004.404845830337;6029.879402411927;6043.244594425697;6152.230971128609;6204.968975155186;6356.872862542723;788.3574886467511;821.9013718353716;925.3898266502421;1053.8869566519234;1098.5825386353295;32868.34263314768;33314.16714582155;33332.833335833166;33477.330453390794;33834.830825846206;1030.407515678488;1068.1935138970027;1082.3019152646657;1126.1666366888444;1229.244014930829;16009.519714408587;16607.91696041512;16868.156592170344;16910.661786764194;16924.91537542329;539236;541660;545112;546281;547418;58302.4169758297;58422.70788646115;58429.831403371696;58512.41487585155;58528.707356462946;1253.8996880249558;1294.0618051912477;1345.7375811716818;1395.6789938314243;1404.192589261179;30168.71418508122;33622;33738.662613373555;33775.83112084424;33777.662223377454;51291.2306315413;51880.48119518757;51901.7404912973;51928.48071519237;51960.96078078493;431412;431841;432441;434462;435021;27697;27974.300642483286;28820;28984.420311594073;29995.70004299929;48960.4092923641;49601;49767;50670.23994640157;50714.23928641;40718;43419;43698.34452483152;43904.34143487786;44358.55641443544;42324.78837605792;42545;42961.3555796657;43761.56238437575;44810.775946121365;38480.30290181159;41966.291854944764;42035;42237.155256894075;42919.7854010728;34511.4823277656;37941.430878536295;37973.43039854349;38385.808070959465;39182.608173919034;28059.438811223252;28450.715492844807;28726.85636571804;28734.281642959093;28773.56839647449 18:37:43 INFO - PROCESS | 7313 | |2;jquery.html;349.8495646871844;373.26280595823243;373.3447799112559;380.1067491395221;388.34176071558704;14383.568492945202;18668.906655466737;19387.612247755053;19902;20206.09072591737;18108.185131669074;19356.612867742635;19729.605407891835;19766.70349944752;19790.208391664317;5597.832065038046;5872.941270587282;5873.236479257691;5875.030619947704;5881.500072493843;5584.497395234428;5828;5848.736806843681;5876.00107981642;5886.735096920627;5839.9124013139835;5847.590668653196;5851;5851.180834683127;5862.941370586283;5605.7197140143035;5666.348369937448;5753.7986170484255;5757.453041961007;5780.7687692492245;5081.695098294114;5095.464976177522;5097.7196254206;5099.08216521026;5103.132467480537;21234.150633974623;21934;23842.046318147255;23916.162934297216;24001.639975400383;2319.826013049011;2516.6602508661463;2735.9603350726798;2896.5799959006035;2925.692802255775;9691.27315451344;10050.597976080948;10128.696139115838;10232.59069637221;10246.69259922196;1930.3533316339044;1941.2235105957589;1959;1964.430315208588;1970.6782152648059;75200.62399687967;75964;77256;83930.16069839346;88476.11523884743;10183.898161018295;10495.790084198274;10746.892531074589;11176.60881869147;11351.886481135249;14351.210683412513;14428.134311941318;14434.639134021523;14494.420223191166;14534.200618965848;7667.654955527015;8093.919060809317;8122.268995790408;8173;8225.753227403187;7635.121960974448;7658.195889431641;7885.093214280317;7892.250236227664;7918.524888506708;4262.573742625775;4307.310830267143;4423.049044455457;4470.865874023784;4817.566419022305;1923.0769230769242;1933.6422761789115;1953.199188332805;1977.2189984956149;1994.1026538057642;884.5356188001351;961.1589858873485;1001.9198464122853;1007.37039350406;1015.3806178231273;18571.907140464482;19926.70109948352;20163.596728065357;20164.495887602927;20316.28892988738;967.9854802177973;979.7844474215661;982.5382070426888;989.4854675568807;1069.502681253221;10350.792984140275;10791.703126243016;10821.621243256448;11064.612738554111;11419.143564232716;517557;529664;529856;535833;541483;69054.74925132788;72753.6362318185;93695.53152234331;94809;100237;1006.2453160129902;1033.834586466162;1034.1261633919326;1038.781855810273;1044.5404022230193;17323.393681221096;17662.64674706473;18240;18400.447986560426;18512.814871851107;43265.3510197341;44248.336274955254;44319.33520997252;44393.33409998788;44559.10881782411;55053;56557.71721141368;57032;62058.68970655118;62247.37752622416;8094.676212951416;8095.3809238151425;8156.796080097808;8218.178182181735;8323.334133269445;12022.338771367658;12324;12330.63008109758;12430.56493022776;12717.491300347883;5718.51392631631;8676.913230867864;8981.281497480055;10938.726531836768;11024.952629500185;5612.607117501736;8281.254687078192;11161.944190278997;11185.161112916727;11331.716707082389;11017.504212310625;11152.275102118336;11323.773524529628;11505.137114716263;11583.305001699924;7777.650005749868;7945.324647405036;7981.161977992228;8207;8606.483610983361;10127.64553240633;10540.262181647211;10686.946565267124;10824.350538967687;10997.505112270128 18:37:43 INFO - PROCESS | 7313 | |3;mootools.html;1409.984810936126;1492.1718446262323;1598.5444148417696;1751.623400968793;1771.627958128793;8371.121032291605;8915.242204412616;8918.554072296383;8921.910780892189;8927.151920567552;7474.289942455458;8372.246497815195;8624.568771561413;8649.870251946226;8736.475811451317;2391.569517486856;2431.963520547193;2432.1244352033254;2432.124435203334;2433.6714543536664;2315.8610483371053;2335.8948847301826;2372.667826504282;2378.9643155352683;2389.928302150938;2357.3752955466816;2378.940526486834;2380.083667787897;2385.5586716457424;2386.976130238693;2313.2135074074813;2321.8258630602604;2328.3131476214558;2328.3597010822073;2371.039728909794;2263.5699217148854;2290.965635515452;2292.3008482412856;2292.449812045114;2295.7704229577075;8551.914480855236;8604.655813767442;8617.052124266325;8617.267532259766;8625.482471051695;5002.424721157041;5158.277841102211;5160.92258616121;5173.456787037345;5179.300794392784;3387.7797943133864;3411.64177761334;3448.896533104011;3456.0668619472603;3481.529993450877;1145.593314373402;1161.7095726068483;1161.8257261410772;1197.2935967778965;1198.2870192235694;12003.879961200364;12040.21738586989;12076.577319793765;12089.57686480969;12130.27218366892;4357.9782101089295;4590.954090459053;4640.8375706850775;4745.976270118696;4748.0978614063215;10156.796864062677;10160;10164;10171.84742228867;10183;5158.690478571298;5168.508991645787;5171.043356978971;5173.663711858792;5176.430592634807;4005.663411005278;4198.265303571863;4238.724482908663;4319.552949761802;4757.35775670287;3977.6817854572146;4032.233875563692;4092.3043082676018;4097.385392191195;4111.9177616448105;1259.1941157659105;1274.9681257968625;1280.8783165599252;1286.8391451068617;1301.1282440764521;765.1545042727817;858.3776761847736;874.776931882366;888.5113187746842;1014.5383850348074;6044.455998960115;6175.475084617768;6202.534809889276;6206.534509911684;6538.967305163444;959.3572306554626;975.3757595139117;990.4255531791543;1007.7631756537207;1019.1948360794889;4078.469798926121;4171.554947605896;4177.624013838769;4256.106217694319;4278.507971583308;10690.198235132397;10826.458677066115;10983.61557345505;11034;11053;10805.081568066802;10918.344899306067;10929.56281748721;10950.780984380268;10990.285631433931;7850.2934735872905;8160.6327715252955;8266.628001739937;8277.875831862528;8280.668773249123;6216.658083805434;6248.250209974832;6282.434580887742;6314.4632706218645;6318.810435686937;10239.129673977497;10328.741781455523;10329.276950613385;10357.067863892285;10461.843072353768;9100.726978190665;9864.112229899343;10224.591016359262;10230.6419275325;10528.736781580521;7524.134724506752;7659.540427574363;7763.253268014877;7965.920340796518;7973.8405231896195;8297.25324720778;8350.665973360998;8356.749297521084;8438.156184381513;8517.063123056583;7660.5403675779635;7751.922480775345;7772.222777722182;7791.4935529190425;7869.881951770613;7518.812029699301;7595.240475952361;7614.2766437188375;7646.9617651911385;7770.53376797394;7056.717731290896;7108.324709152622;7116.750913718202;7117.110361204849;7177.856442871218;5190.299309593157;5334.759935802976;5367.597430192673;5376.059189641719;5427.5657947363325;5095.796168153232;5114.693118412758;5159.974200128975;5191.16941289407;5378.892422151457 18:37:43 INFO - PROCESS | 7313 | |4;prototype.html;319.9808011519311;321.86964279466815;329.92246821996827;334.55838293452655;335.6190723528794;5116.565091967187;5217.973910130453;5290.497402746738;5295.947040529593;5373.32833395826;5180.948190518094;5224.268602395668;5225.686458812474;5270.183121616155;5324;4176.958230417687;4177.644900183488;4194.203101410738;4195.916081678364;4201.873943781676;3979.462772525701;4057.797110144496;4115.9588404115875;4128.112455821998;4137.8758637240935;4093.1199792044704;4096.467459230311;4101.384792281152;4103.958960410388;4109.095998880257;4069.5930406959365;4077.673786097135;4094.3653733671326;4102.774347410891;4117.3618089196225;3624.4563315502623;3629.3467175908327;3633.94549081764;3647;3648.5986541480415;5054.671446356012;5057.519535644108;5122;5137.948620513822;5152.252923326097;4683.320918466803;4699.0161247607675;4725;4725.291206319045;4727.385439892826;5457.454254574511;5493.818828951755;5532.119079512985;5532.889342213133;5586.24585680936;3793.7344385892998;3956.3274243378405;3968.1071758854314;3975.1254723960683;4010.9972506873282;5702.201691763156;5724.656520608777;5731.914021289684;5752.769889204426;5781.277340332501;4066.7356621819486;4074.1444296698023;4078.734882232705;4086.897827554335;4112.321466957978;3889.4554762333296;4065.715399921977;4078.938815917764;4082.8979275517754;4169.437125988014;4673.808178914355;4685.20351540239;4693.108309421199;4694.694844835075;4714.339992401066;5503.697296648643;5503.697296648643;5571.526420254243;5596.516923015404;5596.97201513998;1323.0341850449227;1352.6821197018692;1387.7155183187422;1407.8099456573436;1413.4063693248654;913.0869130869131;942.3780304998805;956.0344052507018;957.1146689312358;958.9760255993656;5286.233496143038;5294.338207724035;5300.469953004668;5307.548858347007;5316.7075810830775;5600.507774040594;5618.2415373924;5625.943740562624;5678.4605462480995;5692.430756924275;5177.948220517822;5289.523942845162;5338.172583249602;5361.705106219196;5392.730363481889;2611.3341098020264;2684.2886635041727;2684.9126103927942;2697.770689491376;2711.959320610192;5192.792288308501;5213.661112027706;5258.447862974409;5260.158374660036;5262.210668399694;4782.521747825259;4999.325091112653;5055;5068.189089745623;5166.974165129151;5109.284700141983;5127.769335359561;5138.640295179302;5139.691618502902;5167.483251674953;4998.825041123543;5007.574356179765;5026.346574945233;5026.421961474331;5032.773525191448;4781.737004464649;4891.1685013548495;4898.828541001048;4909.631777616764;4967.950320496749;5136.152534831711;5142.948570514247;5159.458256883124;5178.948210517847;5203.401608815035;3707.5169932026324;3741.0273328934127;3749.587545370062;3791.184895247535;3802.4486449465226;4842.232585106482;5049.873753156054;5103.392431384103;5111.437741848468;5415.945840541544;5393.325834270716;5410.2696136022705;5555.527780138734;5562.527185189304;5568.749406276646;5503.477169668875;5509.724513774215;5530.474604912527;5552.139418390156;5555.388907220122;5180.300659410931;5399.2980912481125;5448.482394172547;5501.532369748456;5531.059719847555;5532;5558.582561446967;5564.693941833076;5569.303837020372;5569.944300557105;5441.401445841034;5454.699991500347;5469.14049223266;5470.617056806144;5492.4507549246355 18:37:43 INFO - PROCESS | 7313 | |5;yui.html;755.3239850333952;778.420077042603;788.5820515126982;820.4461988157993;850.2177996243455;14270.71458570828;14672.77990830137;14737.189454579993;14752.336144873492;14861.331240094194;15016.399344026253;15101.92449037756;15107.169105699182;15124.697506049872;15219.84780152198;648.2027106658812;777.8055486128468;793.6944276453563;795.8845967334732;815.2866242038217;798.1260519730914;799.9600019998977;803.7227156630953;803.9879401808978;804.1355542791493;846.2087947768825;847.0597636623343;847.5804476784001;847.5973912391627;848.1052489623445;685.1264637587117;803.9477433966773;842.7219017724136;844.4511067805914;844.9733573707944;619.2197830733263;626.8432891777056;627.0093252660785;627.6516533324003;628.4972022382121;14031.64920876976;14201.147931124166;14234.786478202835;14325.49860754879;14755;14528.418863245457;14702.338394772261;14839.10965342083;14852.702945941128;15003.699926001418;12904.956337734375;13087.14933529327;13173.538926137635;13348.466061357534;13673.931630341884;4344.565543445662;4346.087321662423;4431.5789999950275;4563.520830312836;4569.565891240327;83618.16381836226;83784.16215837885;83882.16117838805;87411.68882466768;88963.55518222433;11883.16817822744;12010;12064.336461494526;12083.214591051554;12245;22484.662730059063;22542.098316067175;22543.88728056382;22588.54822903533;22621.660675089886;8588.47550585468;8636;8672.6530938763;8858.911410885808;9051.637934482678;4838.298446725205;4911.3369695090705;4937.629677774181;5003.224500202475;5161.019406312788;3286;3368.781029233141;3370.6966373026544;3377.5440315557576;3382.6955573998457;599.8440405494515;604.274870155806;621.6984762390327;625.2902955145942;627.1157667688537;653.2618141500075;653.2879161713755;658.3811217455673;700.9544379615411;718.2817182817183;9445.574949127305;9502.572384242725;9516;9759.170470509944;9824.852627210597;571.6255852416674;583.2534356024296;615.7106160104744;623.7130919776863;702.3573430311208;4554.362389265505;4671.696339737906;4715.151272770866;4755.904881902343;4804.663673542818;117788.41105794416;119826;120569.3402192757;122600;125382.3730881358;96281.51859240659;97458.512707436;98080.50959745156;98170.50914745523;99580;72932;87082.56458717793;87426.56286718653;88445.11554884497;90873.54563227142;9903;9944.105030547285;10041.347312424668;10126.291159618757;10134.594616215269;49720;50020;50452.74773626108;51006;52130.739346303024;73160;77598.28406005763;78491.60754196421;78518.21481785108;79133;12926.482940682268;13213.405396756976;13488.6627834301;13769.242691652054;13893;14202.502912398017;14582.562523124323;14610.196439195946;14716.411343546566;15493.380264789283;12908.935455322664;12963.481460741465;13294.534691285759;14234.928825355806;14571.635709107357;13479.460821567027;13801.585952421443;13941.790873136708;14023.298835058002;14341.64145896361;14060.578182654537;14141.929290353894;14221;14359.497417590333;14435.494757683431;11019.173561982723;11090.667279981613;11492.942535287604;11603.245789023686;11934.403279836148;11894.583689570823;11901.880981190423;12040.698982525508;12137.332446715516;12154.331511766584 18:37:43 INFO - PROCESS | 7313 | __end_tp_report 18:37:43 INFO - PROCESS | 7313 | __start_cc_report 18:37:43 INFO - PROCESS | 7313 | _x_x_mozilla_cycle_collect,963 18:37:43 INFO - PROCESS | 7313 | __end_cc_report 18:37:43 INFO - PROCESS | 7313 | __startTimestamp1469151463635__endTimestamp 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | ------- Summary: start ------- 18:37:43 INFO - PROCESS | 7313 | Number of tests: 6 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | [#0] dojo.html Cycles:165 Average:5336.29 Median:5543.45 stddev:612.64 (11.1%) stddev-sans-first:587.79 18:37:43 INFO - PROCESS | 7313 | Values: 3054.2 3075.3 3109.4 3110.1 3121.3 5219.3 5329.1 5338.1 5442.9 5454.2 5365.4 5374.0 5400.2 5446.1 5503.6 4900.4 4901.9 4939.9 4952.6 5051.2 4995.5 5029.0 5029.6 5034.4 5041.9 4780.5 4823.5 4829.0 4910.7 4913.9 4799.7 4822.4 4840.6 4857.4 4917.3 4750.0 4819.6 4846.4 4853.2 4870.9 5344.6 5374.4 5393.5 5402.8 5412.5 5443.0 5600.0 5650.9 5660.3 5738.9 5701.7 5729.9 5773.2 5779.8 5790.7 5184.6 5392.6 5398.5 5398.5 5405.4 5812.2 5864.0 5877.0 5961.6 5969.5 5665.4 5668.2 5719.0 5737.0 5738.1 5590.3 5773.9 5819.9 5831.1 5834.1 5602.1 5644.5 5644.9 5745.2 5746.0 5685.0 5701.5 5706.1 5726.2 5768.8 4293.6 4315.8 4333.6 4351.1 4373.4 3783.8 3799.1 3862.7 3908.1 3927.5 5397.7 5397.9 5438.5 5449.7 5484.5 5801.3 5802.5 5813.5 5815.7 5857.3 5265.7 5353.9 5396.9 5490.3 5492.7 4956.1 4957.1 4991.4 4993.7 5002.6 5859.2 5890.1 5897.4 5910.5 5911.6 5583.1 5642.2 5745.8 5759.5 5759.6 5837.9 5895.9 5904.9 5933.0 5956.4 5553.6 5609.7 5622.2 5623.4 5627.9 5522.3 5733.3 5739.6 5751.2 5757.3 5618.1 5707.4 5717.9 5749.2 5773.7 5658.3 5694.3 5703.8 5708.7 5710.1 5405.9 5499.3 5650.6 5661.9 5669.3 5609.5 5700.6 5708.3 5723.3 5731.7 5529.9 5531.0 5538.2 5543.4 5545.3 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | [#1] ext.html Cycles:180 Average:57968.95 Median:237866.57 stddev:113436.90 (47.7%) stddev-sans-first:113673.37 18:37:43 INFO - PROCESS | 7313 | Values: 608.1 629.7 631.1 645.8 659.4 31200.0 43117.0 43694.0 43732.0 46755.5 44793.0 45109.3 45202.3 45223.8 45245.5 8843.9 8994.6 8997.0 8997.8 9010.0 8931.9 8993.0 9017.8 9018.4 9019.6 9057.2 9097.6 9105.4 9105.4 9112.2 9006.8 9022.7 9042.4 9072.2 9077.8 8884.1 8953.3 8955.9 8966.2 8972.7 42960.4 43292.1 43301.8 43372.1 43392.6 3065.4 3437.3 3463.4 3500.9 3508.7 33783.7 33912.3 34256.9 35353.8 35542.3 3984.4 4068.0 4107.0 4257.6 4340.8 250834.0 252368.0 252569.0 252769.0 252853.0 58974.0 59539.7 59571.4 59611.4 59745.7 55308.7 56536.0 56562.4 59703.0 62309.4 15777.2 16140.8 16207.3 16693.0 16693.4 13252.1 13316.5 13403.3 13428.7 13462.0 16633.3 16858.4 16915.9 16921.4 17004.4 6029.9 6043.2 6152.2 6205.0 6356.9 788.4 821.9 925.4 1053.9 1098.6 32868.3 33314.2 33332.8 33477.3 33834.8 1030.4 1068.2 1082.3 1126.2 1229.2 16009.5 16607.9 16868.2 16910.7 16924.9 539236.0 541660.0 545112.0 546281.0 547418.0 58302.4 58422.7 58429.8 58512.4 58528.7 1253.9 1294.1 1345.7 1395.7 1404.2 30168.7 33622.0 33738.7 33775.8 33777.7 51291.2 51880.5 51901.7 51928.5 51961.0 431412.0 431841.0 432441.0 434462.0 435021.0 27697.0 27974.3 28820.0 28984.4 29995.7 48960.4 49601.0 49767.0 50670.2 50714.2 40718.0 43419.0 43698.3 43904.3 44358.6 42324.8 42545.0 42961.4 43761.6 44810.8 38480.3 41966.3 42035.0 42237.2 42919.8 34511.5 37941.4 37973.4 38385.8 39182.6 28059.4 28450.7 28726.9 28734.3 28773.6 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | [#2] jquery.html Cycles:180 Average:29792.82 Median:373.30 stddev:87310.92 (23388.7%) stddev-sans-first:87527.86 18:37:43 INFO - PROCESS | 7313 | Values: 349.8 373.3 373.3 380.1 388.3 14383.6 18668.9 19387.6 19902.0 20206.1 18108.2 19356.6 19729.6 19766.7 19790.2 5597.8 5872.9 5873.2 5875.0 5881.5 5584.5 5828.0 5848.7 5876.0 5886.7 5839.9 5847.6 5851.0 5851.2 5862.9 5605.7 5666.3 5753.8 5757.5 5780.8 5081.7 5095.5 5097.7 5099.1 5103.1 21234.2 21934.0 23842.0 23916.2 24001.6 2319.8 2516.7 2736.0 2896.6 2925.7 9691.3 10050.6 10128.7 10232.6 10246.7 1930.4 1941.2 1959.0 1964.4 1970.7 75200.6 75964.0 77256.0 83930.2 88476.1 10183.9 10495.8 10746.9 11176.6 11351.9 14351.2 14428.1 14434.6 14494.4 14534.2 7667.7 8093.9 8122.3 8173.0 8225.8 7635.1 7658.2 7885.1 7892.3 7918.5 4262.6 4307.3 4423.0 4470.9 4817.6 1923.1 1933.6 1953.2 1977.2 1994.1 884.5 961.2 1001.9 1007.4 1015.4 18571.9 19926.7 20163.6 20164.5 20316.3 968.0 979.8 982.5 989.5 1069.5 10350.8 10791.7 10821.6 11064.6 11419.1 517557.0 529664.0 529856.0 535833.0 541483.0 69054.7 72753.6 93695.5 94809.0 100237.0 1006.2 1033.8 1034.1 1038.8 1044.5 17323.4 17662.6 18240.0 18400.4 18512.8 43265.4 44248.3 44319.3 44393.3 44559.1 55053.0 56557.7 57032.0 62058.7 62247.4 8094.7 8095.4 8156.8 8218.2 8323.3 12022.3 12324.0 12330.6 12430.6 12717.5 5718.5 8676.9 8981.3 10938.7 11025.0 5612.6 8281.3 11161.9 11185.2 11331.7 11017.5 11152.3 11323.8 11505.1 11583.3 7777.7 7945.3 7981.2 8207.0 8606.5 10127.6 10540.3 10686.9 10824.4 10997.5 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | [#3] mootools.html Cycles:180 Average:5797.33 Median:4474.47 stddev:3263.14 (72.9%) stddev-sans-first:3255.63 18:37:43 INFO - PROCESS | 7313 | Values: 1410.0 1492.2 1598.5 1751.6 1771.6 8371.1 8915.2 8918.6 8921.9 8927.2 7474.3 8372.2 8624.6 8649.9 8736.5 2391.6 2432.0 2432.1 2432.1 2433.7 2315.9 2335.9 2372.7 2379.0 2389.9 2357.4 2378.9 2380.1 2385.6 2387.0 2313.2 2321.8 2328.3 2328.4 2371.0 2263.6 2291.0 2292.3 2292.4 2295.8 8551.9 8604.7 8617.1 8617.3 8625.5 5002.4 5158.3 5160.9 5173.5 5179.3 3387.8 3411.6 3448.9 3456.1 3481.5 1145.6 1161.7 1161.8 1197.3 1198.3 12003.9 12040.2 12076.6 12089.6 12130.3 4358.0 4591.0 4640.8 4746.0 4748.1 10156.8 10160.0 10164.0 10171.8 10183.0 5158.7 5168.5 5171.0 5173.7 5176.4 4005.7 4198.3 4238.7 4319.6 4757.4 3977.7 4032.2 4092.3 4097.4 4111.9 1259.2 1275.0 1280.9 1286.8 1301.1 765.2 858.4 874.8 888.5 1014.5 6044.5 6175.5 6202.5 6206.5 6539.0 959.4 975.4 990.4 1007.8 1019.2 4078.5 4171.6 4177.6 4256.1 4278.5 10690.2 10826.5 10983.6 11034.0 11053.0 10805.1 10918.3 10929.6 10950.8 10990.3 7850.3 8160.6 8266.6 8277.9 8280.7 6216.7 6248.3 6282.4 6314.5 6318.8 10239.1 10328.7 10329.3 10357.1 10461.8 9100.7 9864.1 10224.6 10230.6 10528.7 7524.1 7659.5 7763.3 7965.9 7973.8 8297.3 8350.7 8356.7 8438.2 8517.1 7660.5 7751.9 7772.2 7791.5 7869.9 7518.8 7595.2 7614.3 7647.0 7770.5 7056.7 7108.3 7116.8 7117.1 7177.9 5190.3 5334.8 5367.6 5376.1 5427.6 5095.8 5114.7 5160.0 5191.2 5378.9 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | [#4] prototype.html Cycles:180 Average:4510.70 Median:5114.00 stddev:1305.99 (25.5%) stddev-sans-first:1271.21 18:37:43 INFO - PROCESS | 7313 | Values: 320.0 321.9 329.9 334.6 335.6 5116.6 5218.0 5290.5 5295.9 5373.3 5180.9 5224.3 5225.7 5270.2 5324.0 4177.0 4177.6 4194.2 4195.9 4201.9 3979.5 4057.8 4116.0 4128.1 4137.9 4093.1 4096.5 4101.4 4104.0 4109.1 4069.6 4077.7 4094.4 4102.8 4117.4 3624.5 3629.3 3633.9 3647.0 3648.6 5054.7 5057.5 5122.0 5137.9 5152.3 4683.3 4699.0 4725.0 4725.3 4727.4 5457.5 5493.8 5532.1 5532.9 5586.2 3793.7 3956.3 3968.1 3975.1 4011.0 5702.2 5724.7 5731.9 5752.8 5781.3 4066.7 4074.1 4078.7 4086.9 4112.3 3889.5 4065.7 4078.9 4082.9 4169.4 4673.8 4685.2 4693.1 4694.7 4714.3 5503.7 5503.7 5571.5 5596.5 5597.0 1323.0 1352.7 1387.7 1407.8 1413.4 913.1 942.4 956.0 957.1 959.0 5286.2 5294.3 5300.5 5307.5 5316.7 5600.5 5618.2 5625.9 5678.5 5692.4 5177.9 5289.5 5338.2 5361.7 5392.7 2611.3 2684.3 2684.9 2697.8 2712.0 5192.8 5213.7 5258.4 5260.2 5262.2 4782.5 4999.3 5055.0 5068.2 5167.0 5109.3 5127.8 5138.6 5139.7 5167.5 4998.8 5007.6 5026.3 5026.4 5032.8 4781.7 4891.2 4898.8 4909.6 4968.0 5136.2 5142.9 5159.5 5178.9 5203.4 3707.5 3741.0 3749.6 3791.2 3802.4 4842.2 5049.9 5103.4 5111.4 5415.9 5393.3 5410.3 5555.5 5562.5 5568.7 5503.5 5509.7 5530.5 5552.1 5555.4 5180.3 5399.3 5448.5 5501.5 5531.1 5532.0 5558.6 5564.7 5569.3 5569.9 5441.4 5454.7 5469.1 5470.6 5492.5 18:37:43 INFO - PROCESS | 7313 | 18:37:43 INFO - PROCESS | 7313 | [#5] yui.html Cycles:180 Average:21406.14 Median:4735.53 stddev:30956.29 (653.7%) stddev-sans-first:31004.29 18:37:43 INFO - PROCESS | 7313 | Values: 755.3 778.4 788.6 820.4 850.2 14270.7 14672.8 14737.2 14752.3 14861.3 15016.4 15101.9 15107.2 15124.7 15219.8 648.2 777.8 793.7 795.9 815.3 798.1 800.0 803.7 804.0 804.1 846.2 847.1 847.6 847.6 848.1 685.1 803.9 842.7 844.5 845.0 619.2 626.8 627.0 627.7 628.5 14031.6 14201.1 14234.8 14325.5 14755.0 14528.4 14702.3 14839.1 14852.7 15003.7 12905.0 13087.1 13173.5 13348.5 13673.9 4344.6 4346.1 4431.6 4563.5 4569.6 83618.2 83784.2 83882.2 87411.7 88963.6 11883.2 12010.0 12064.3 12083.2 12245.0 22484.7 22542.1 22543.9 22588.5 22621.7 8588.5 8636.0 8672.7 8858.9 9051.6 4838.3 4911.3 4937.6 5003.2 5161.0 3286.0 3368.8 3370.7 3377.5 3382.7 599.8 604.3 621.7 625.3 627.1 653.3 653.3 658.4 701.0 718.3 9445.6 9502.6 9516.0 9759.2 9824.9 571.6 583.3 615.7 623.7 702.4 4554.4 4671.7 4715.2 4755.9 4804.7 117788.4 119826.0 120569.3 122600.0 125382.4 96281.5 97458.5 98080.5 98170.5 99580.0 72932.0 87082.6 87426.6 88445.1 90873.5 9903.0 9944.1 10041.3 10126.3 10134.6 49720.0 50020.0 50452.7 51006.0 52130.7 73160.0 77598.3 78491.6 78518.2 79133.0 12926.5 13213.4 13488.7 13769.2 13893.0 14202.5 14582.6 14610.2 14716.4 15493.4 12908.9 12963.5 13294.5 14234.9 14571.6 13479.5 13801.6 13941.8 14023.3 14341.6 14060.6 14141.9 14221.0 14359.5 14435.5 11019.2 11090.7 11492.9 11603.2 11934.4 11894.6 11901.9 12040.7 12137.3 12154.3 18:37:43 INFO - PROCESS | 7313 | -------- Summary: end -------- 18:37:43 INFO - PROCESS | 7313 | 18:37:44 INFO - TEST-INFO | 7313: exit 0 18:37:44 INFO - TEST-OK | dromaeo_css | took 1113432ms 18:37:44 INFO - TEST-START | kraken 18:37:44 INFO - operating with platform_type : linux_ 18:37:44 INFO - Initialising browser for kraken test... 18:37:44 INFO - TEST-INFO | started process 7505 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN9uf_3/profile http://localhost:34365/getInfo.html) 18:37:47 INFO - PROCESS | 7505 | JavaScript error: , line 0: uncaught exception: undefined 18:37:47 INFO - PROCESS | 7505 | __metrics Screen width/height:1600/1200 18:37:47 INFO - PROCESS | 7505 | colorDepth:24 18:37:47 INFO - PROCESS | 7505 | Browser inner width/height: 1024/697 18:37:47 INFO - PROCESS | 7505 | __metrics 18:37:48 INFO - PROCESS | 7505 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:37:48 INFO - PROCESS | 7505 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:49 INFO - PROCESS | 7505 | console.error: PushService: 18:37:49 INFO - PROCESS | 7505 | stateChangeProcessEnqueue: Error transitioning state 18:37:49 INFO - PROCESS | 7505 | UnknownError 18:37:49 INFO - TEST-INFO | 7505: exit 0 18:37:49 INFO - Browser initialized. 18:37:49 INFO - Running cycle 1/1 for kraken test... 18:37:49 INFO - TEST-INFO | started process 7591 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN9uf_3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:01 INFO - PROCESS | 7591 | RSS: Main: 158089216 18:38:01 INFO - PROCESS | 7591 | 18:38:03 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34365/tests/kraken/driver.html?testName=audio-beat-detection) 18:38:04 INFO - PROCESS | 7591 | RSS: Main: 213774336 18:38:04 INFO - PROCESS | 7591 | 18:38:07 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34365/tests/kraken/driver.html?testName=audio-dft) 18:38:08 INFO - PROCESS | 7591 | RSS: Main: 192946176 18:38:08 INFO - PROCESS | 7591 | 18:38:11 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34365/tests/kraken/driver.html?testName=audio-fft) 18:38:11 INFO - PROCESS | 7591 | RSS: Main: 270331904 18:38:11 INFO - PROCESS | 7591 | 18:38:13 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34365/tests/kraken/driver.html?testName=audio-oscillator) 18:38:14 INFO - PROCESS | 7591 | RSS: Main: 181334016 18:38:14 INFO - PROCESS | 7591 | 18:38:16 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34365/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:17 INFO - PROCESS | 7591 | RSS: Main: 185745408 18:38:17 INFO - PROCESS | 7591 | 18:38:20 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34365/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:20 INFO - PROCESS | 7591 | RSS: Main: 220786688 18:38:20 INFO - PROCESS | 7591 | 18:38:24 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34365/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:24 INFO - PROCESS | 7591 | RSS: Main: 221724672 18:38:24 INFO - PROCESS | 7591 | 18:38:27 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34365/tests/kraken/driver.html?testName=json-parse-financial) 18:38:28 INFO - PROCESS | 7591 | RSS: Main: 221331456 18:38:28 INFO - PROCESS | 7591 | 18:38:29 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34365/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:30 INFO - PROCESS | 7591 | RSS: Main: 184889344 18:38:30 INFO - PROCESS | 7591 | 18:38:32 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:32 INFO - PROCESS | 7591 | RSS: Main: 191725568 18:38:32 INFO - PROCESS | 7591 | 18:38:34 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:35 INFO - PROCESS | 7591 | RSS: Main: 195796992 18:38:35 INFO - PROCESS | 7591 | 18:38:38 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:38 INFO - PROCESS | 7591 | RSS: Main: 189497344 18:38:38 INFO - PROCESS | 7591 | 18:38:41 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:41 INFO - PROCESS | 7591 | RSS: Main: 194465792 18:38:41 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | Cycle 1(1): loaded http://localhost:34365/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34365/tests/kraken/driver.html?testName=ai-astar) 18:38:43 INFO - PROCESS | 7591 | RSS: Main: 245051392 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | __start_tp_report 18:38:43 INFO - PROCESS | 7591 | _x_x_mozilla_page_load 18:38:43 INFO - PROCESS | 7591 | _x_x_mozilla_page_load_details 18:38:43 INFO - PROCESS | 7591 | |i|pagename|runs| 18:38:43 INFO - PROCESS | 7591 | |0;ai-astar;104;111;111;111;112;110;112;111;111;111 18:38:43 INFO - PROCESS | 7591 | |1;audio-beat-detection;154;123;120;188;124;120;147;117;159;121 18:38:43 INFO - PROCESS | 7591 | |2;audio-dft;161;162;160;160;160;161;163;160;162;162 18:38:43 INFO - PROCESS | 7591 | |3;audio-fft;74;76;74;122;75;75;74;75;112;76 18:38:43 INFO - PROCESS | 7591 | |4;audio-oscillator;83;83;120;83;83;84;113;84;84;130 18:38:43 INFO - PROCESS | 7591 | |5;imaging-gaussian-blur;116;117;117;116;117;117;116;118;116;116 18:38:43 INFO - PROCESS | 7591 | |6;imaging-darkroom;146;146;145;146;146;146;146;146;147;145 18:38:43 INFO - PROCESS | 7591 | |7;imaging-desaturate;95;95;95;95;95;96;96;96;95;95 18:38:43 INFO - PROCESS | 7591 | |8;json-parse-financial;52;63;54;52;53;51;53;54;53;52 18:38:43 INFO - PROCESS | 7591 | |9;json-stringify-tinderbox;62;63;62;62;63;63;63;62;63;63 18:38:43 INFO - PROCESS | 7591 | |10;stanford-crypto-aes;76;76;78;80;76;75;75;75;75;80 18:38:43 INFO - PROCESS | 7591 | |11;stanford-crypto-ccm;124;134;136;130;119;121;128;120;159;122 18:38:43 INFO - PROCESS | 7591 | |12;stanford-crypto-pbkdf2;155;155;154;160;152;163;155;156;159;161 18:38:43 INFO - PROCESS | 7591 | |13;stanford-crypto-sha256-iterative;63;63;63;62;62;63;61;61;65;63 18:38:43 INFO - PROCESS | 7591 | __end_tp_report 18:38:43 INFO - PROCESS | 7591 | __start_cc_report 18:38:43 INFO - PROCESS | 7591 | _x_x_mozilla_cycle_collect,1705 18:38:43 INFO - PROCESS | 7591 | __end_cc_report 18:38:43 INFO - PROCESS | 7591 | __startTimestamp1469151523854__endTimestamp 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | ------- Summary: start ------- 18:38:43 INFO - PROCESS | 7591 | Number of tests: 14 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.32 (2.1%) stddev-sans-first:0.60 18:38:43 INFO - PROCESS | 7591 | Values: 104.0 111.0 111.0 111.0 112.0 110.0 112.0 111.0 111.0 111.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:135.50 stddev:23.74 (17.5%) stddev-sans-first:24.40 18:38:43 INFO - PROCESS | 7591 | Values: 154.0 123.0 120.0 188.0 124.0 120.0 147.0 117.0 159.0 121.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.17 18:38:43 INFO - PROCESS | 7591 | Values: 161.0 162.0 160.0 160.0 160.0 161.0 163.0 160.0 162.0 162.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#3] audio-fft Cycles:10 Average:83.30 Median:75.00 stddev:17.93 (23.9%) stddev-sans-first:18.70 18:38:43 INFO - PROCESS | 7591 | Values: 74.0 76.0 74.0 122.0 75.0 75.0 74.0 75.0 112.0 76.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.00 stddev:18.60 (22.4%) stddev-sans-first:19.24 18:38:43 INFO - PROCESS | 7591 | Values: 83.0 83.0 120.0 83.0 83.0 84.0 113.0 84.0 84.0 130.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 18:38:43 INFO - PROCESS | 7591 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 116.0 118.0 116.0 116.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 18:38:43 INFO - PROCESS | 7591 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:38:43 INFO - PROCESS | 7591 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.00 stddev:3.40 (6.4%) stddev-sans-first:3.55 18:38:43 INFO - PROCESS | 7591 | Values: 52.0 63.0 54.0 52.0 53.0 51.0 53.0 54.0 53.0 52.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 18:38:43 INFO - PROCESS | 7591 | Values: 62.0 63.0 62.0 62.0 63.0 63.0 63.0 62.0 63.0 63.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:76.00 stddev:2.01 (2.6%) stddev-sans-first:2.12 18:38:43 INFO - PROCESS | 7591 | Values: 76.0 76.0 78.0 80.0 76.0 75.0 75.0 75.0 75.0 80.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:129.00 stddev:11.99 (9.3%) stddev-sans-first:12.56 18:38:43 INFO - PROCESS | 7591 | Values: 124.0 134.0 136.0 130.0 119.0 121.0 128.0 120.0 159.0 122.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.00 Median:157.50 stddev:3.53 (2.2%) stddev-sans-first:3.67 18:38:43 INFO - PROCESS | 7591 | Values: 155.0 155.0 154.0 160.0 152.0 163.0 155.0 156.0 159.0 161.0 18:38:43 INFO - PROCESS | 7591 | 18:38:43 INFO - PROCESS | 7591 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.17 (1.9%) stddev-sans-first:1.24 18:38:43 INFO - PROCESS | 7591 | Values: 63.0 63.0 63.0 62.0 62.0 63.0 61.0 61.0 65.0 63.0 18:38:43 INFO - PROCESS | 7591 | -------- Summary: end -------- 18:38:43 INFO - PROCESS | 7591 | 18:38:44 INFO - TEST-INFO | 7591: exit 0 18:38:44 INFO - TEST-OK | kraken | took 60589ms 18:38:44 INFO - SUITE-END | took 1174s 18:38:45 INFO - Completed test suite (00:19:34) 18:38:45 INFO - javascript benchmark 18:38:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.2211736007316, 3075.3234288456533, 3109.4403007458677, 3110.0825256549315, 3121.313311071565, 5219.321488206538, 5329.147336426173, 5338.092524270874, 5442.918356224654, 5454.236406903032, 5365.382980957182, 5373.973130134354, 5400.1629747389115, 5446.128619420893, 5503.6147469677135, 4900.362952816129, 4901.90196196075, 4939.901201975958, 4952.579030782388, 5051.242313652963, 4995.500449954993, 5028.994201159764, 5029.572486338648, 5034.446210916814, 5041.949580504184, 4780.474147843717, 4823.493533179036, 4829.034193161347, 4910.729909854975, 4913.926291105637, 4799.7360145192, 4822.397200349957, 4840.588549973221, 4857.368542089541, 4917.262410638397, 4749.952500474986, 4819.638527110481, 4846.36997190363, 4853.247746599282, 4870.902581948377, 5344.5724342052545, 5374.355077390699, 5393.460653934614, 5402.756875940592, 5412.4587541245955, 5442.972785136049, 5599.972000140014, 5650.886982260332, 5660.264165658438, 5738.913916291259, 5701.6864072476, 5729.942700572982, 5773.191753154561, 5779.797707080274, 5790.652560846363, 5184.5593124584075, 5392.622516423852, 5398.4871437213005, 5398.4871437213405, 5405.4324295949145, 5812.215350927655, 5863.970680146657, 5876.971529982322, 5961.582689211714, 5969.462748352669, 5665.433456654301, 5668.206451096849, 5719.0, 5736.971315143398, 5738.139279108083, 5590.273264475673, 5773.855653608609, 5819.912701309484, 5831.096180092092, 5834.095715164156, 5602.075657516546, 5644.491995720405, 5644.8871022579315, 5745.1669507921115, 5746.02317606008, 5684.976704193202, 5701.458361455655, 5706.144078388192, 5726.169705392778, 5768.798092066758, 4293.635041021485, 4315.8489452869, 4333.631641310524, 4351.086271882877, 4373.409589705413, 3783.754055986352, 3799.088218827517, 3862.729608927404, 3908.081600823804, 3927.548331941863, 5397.730113494388, 5397.892042159135, 5438.510534051954, 5449.727513624303, 5484.451554844483, 5801.3038435388025, 5802.535797136219, 5813.5349172066135, 5815.651060936358, 5857.297124345021, 5265.736713164327, 5353.9196912046355, 5396.91904621431, 5490.286262785812, 5492.725363731797, 4956.132676781549, 4957.082939656175, 4991.425986011655, 4993.725345106054, 5002.624803139779, 5859.150423188614, 5890.145928840294, 5897.351291357947, 5910.527157827363, 5911.556633252523, 5583.134614134816, 5642.181883626769, 5745.827625171251, 5759.51044161251, 5759.625624334405, 5837.912431313619, 5895.882082358243, 5904.940950590439, 5933.021051526623, 5956.404359564009, 5553.555715542828, 5609.691466969356, 5622.2128901954575, 5623.409541998195, 5627.915581266366, 5522.30971128609, 5733.283339582552, 5739.5695322850115, 5751.194832723506, 5757.280339957505, 5618.101103823532, 5707.372189059117, 5717.8570535736935, 5749.223854779551, 5773.740181691918, 5658.264425624642, 5694.288213973254, 5703.771849125988, 5708.743106560298, 5710.086386178192, 5405.918911216414, 5499.285092937893, 5650.6327088740745, 5661.886762264649, 5669.31968163823, 5609.49514543693, 5700.600957932905, 5708.343540492897, 5723.3418156912485, 5731.684757338219, 5529.889402212014, 5530.972345138249, 5538.224648549126, 5543.445655434424, 5545.279113715191], "value": 5294.236081487111, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.1182285685755, 629.7229219143577, 631.07547442996, 645.8256270806883, 659.423004870738, 31200.0, 43117.0, 43694.0, 43732.0, 46755.53244467554, 44793.0, 45109.323360149625, 45202.32196517055, 45223.77388113063, 45245.54754452455, 8843.911560884406, 8994.640214391417, 8996.955015224914, 8997.820043599124, 9010.0, 8931.866022009675, 8993.0, 9017.774555636095, 9018.41380310281, 9019.639214431414, 9057.184853363196, 9097.590608422637, 9105.408148470327, 9105.408148470362, 9112.179903808656, 9006.819863602725, 9022.729318120468, 9042.367034307601, 9072.228860546795, 9077.773055673595, 8884.111588841064, 8953.328500362433, 8955.865662015074, 8966.19304262613, 8972.685955991508, 42960.35559466611, 43292.13415731699, 43301.78349108266, 43372.132557348676, 43392.56607433917, 3065.4022465619214, 3437.346904088215, 3463.359278533454, 3500.8949731508096, 3508.7372010273853, 33783.6621633783, 33912.32175356504, 34256.91687748115, 35353.823230883936, 35542.28915421703, 3984.362501999666, 4067.959320406788, 4107.0, 4257.638100761439, 4340.782960851976, 250834.0, 252368.0, 252568.99999999817, 252768.99999999817, 252853.0, 58974.0, 59539.70230148908, 59571.404285957455, 59611.40388596145, 59745.701271493366, 55308.72345638246, 56536.0, 56562.43437565654, 59703.0, 62309.376906230355, 15777.211139442981, 16140.838591614169, 16207.270672819752, 16693.0, 16693.414125658044, 13252.138610990254, 13316.533921312901, 13403.329833508285, 13428.664283392993, 13462.0, 16633.334666613442, 16858.40995565174, 16915.915420423065, 16921.40775072891, 17004.404845830337, 6029.879402411927, 6043.244594425697, 6152.230971128609, 6204.968975155186, 6356.872862542723, 788.3574886467511, 821.9013718353716, 925.3898266502421, 1053.8869566519234, 1098.5825386353295, 32868.34263314768, 33314.16714582155, 33332.833335833166, 33477.330453390794, 33834.830825846206, 1030.407515678488, 1068.1935138970027, 1082.3019152646657, 1126.1666366888444, 1229.244014930829, 16009.519714408587, 16607.91696041512, 16868.156592170344, 16910.661786764194, 16924.91537542329, 539236.0, 541660.0, 545112.0, 546281.0, 547418.0, 58302.4169758297, 58422.70788646115, 58429.831403371696, 58512.41487585155, 58528.707356462946, 1253.8996880249558, 1294.0618051912477, 1345.7375811716818, 1395.6789938314243, 1404.192589261179, 30168.71418508122, 33622.0, 33738.662613373555, 33775.83112084424, 33777.662223377454, 51291.2306315413, 51880.48119518757, 51901.7404912973, 51928.48071519237, 51960.96078078493, 431412.0, 431841.0, 432441.0, 434462.0, 435021.0, 27697.0, 27974.300642483286, 28820.0, 28984.420311594073, 29995.70004299929, 48960.4092923641, 49601.0, 49767.0, 50670.23994640157, 50714.23928641, 40718.0, 43419.0, 43698.34452483152, 43904.34143487786, 44358.55641443544, 42324.78837605792, 42545.0, 42961.3555796657, 43761.56238437575, 44810.775946121365, 38480.30290181159, 41966.291854944764, 42035.0, 42237.155256894075, 42919.7854010728, 34511.4823277656, 37941.430878536295, 37973.43039854349, 38385.808070959465, 39182.608173919034, 28059.438811223252, 28450.715492844807, 28726.85636571804, 28734.281642959093, 28773.56839647449], "value": 19964.113492493936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.8495646871844, 373.26280595823243, 373.3447799112559, 380.1067491395221, 388.34176071558704, 14383.568492945202, 18668.906655466737, 19387.612247755053, 19902.0, 20206.09072591737, 18108.185131669074, 19356.612867742635, 19729.605407891835, 19766.70349944752, 19790.208391664317, 5597.832065038046, 5872.941270587282, 5873.236479257691, 5875.030619947704, 5881.500072493843, 5584.497395234428, 5828.0, 5848.736806843681, 5876.00107981642, 5886.735096920627, 5839.9124013139835, 5847.590668653196, 5851.0, 5851.180834683127, 5862.941370586283, 5605.7197140143035, 5666.348369937448, 5753.7986170484255, 5757.453041961007, 5780.7687692492245, 5081.695098294114, 5095.464976177522, 5097.7196254206, 5099.08216521026, 5103.132467480537, 21234.150633974623, 21934.0, 23842.046318147255, 23916.162934297216, 24001.639975400383, 2319.826013049011, 2516.6602508661463, 2735.9603350726798, 2896.5799959006035, 2925.692802255775, 9691.27315451344, 10050.597976080948, 10128.696139115838, 10232.59069637221, 10246.69259922196, 1930.3533316339044, 1941.2235105957589, 1959.0, 1964.430315208588, 1970.6782152648059, 75200.62399687967, 75964.0, 77256.0, 83930.16069839346, 88476.11523884743, 10183.898161018295, 10495.790084198274, 10746.892531074589, 11176.60881869147, 11351.886481135249, 14351.210683412513, 14428.134311941318, 14434.639134021523, 14494.420223191166, 14534.200618965848, 7667.654955527015, 8093.919060809317, 8122.268995790408, 8173.0, 8225.753227403187, 7635.121960974448, 7658.195889431641, 7885.093214280317, 7892.250236227664, 7918.524888506708, 4262.573742625775, 4307.310830267143, 4423.049044455457, 4470.865874023784, 4817.566419022305, 1923.0769230769242, 1933.6422761789115, 1953.199188332805, 1977.2189984956149, 1994.1026538057642, 884.5356188001351, 961.1589858873485, 1001.9198464122853, 1007.37039350406, 1015.3806178231273, 18571.907140464482, 19926.70109948352, 20163.596728065357, 20164.495887602927, 20316.28892988738, 967.9854802177973, 979.7844474215661, 982.5382070426888, 989.4854675568807, 1069.502681253221, 10350.792984140275, 10791.703126243016, 10821.621243256448, 11064.612738554111, 11419.143564232716, 517557.0, 529664.0, 529856.0, 535833.0, 541483.0, 69054.74925132788, 72753.6362318185, 93695.53152234331, 94809.0, 100237.0, 1006.2453160129902, 1033.834586466162, 1034.1261633919326, 1038.781855810273, 1044.5404022230193, 17323.393681221096, 17662.64674706473, 18240.0, 18400.447986560426, 18512.814871851107, 43265.3510197341, 44248.336274955254, 44319.33520997252, 44393.33409998788, 44559.10881782411, 55053.0, 56557.71721141368, 57032.0, 62058.68970655118, 62247.37752622416, 8094.676212951416, 8095.3809238151425, 8156.796080097808, 8218.178182181735, 8323.334133269445, 12022.338771367658, 12324.0, 12330.63008109758, 12430.56493022776, 12717.491300347883, 5718.51392631631, 8676.913230867864, 8981.281497480055, 10938.726531836768, 11024.952629500185, 5612.607117501736, 8281.254687078192, 11161.944190278997, 11185.161112916727, 11331.716707082389, 11017.504212310625, 11152.275102118336, 11323.773524529628, 11505.137114716263, 11583.305001699924, 7777.650005749868, 7945.324647405036, 7981.161977992228, 8207.0, 8606.483610983361, 10127.64553240633, 10540.262181647211, 10686.946565267124, 10824.350538967687, 10997.505112270128], "value": 9027.790987816225, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.984810936126, 1492.1718446262323, 1598.5444148417696, 1751.623400968793, 1771.627958128793, 8371.121032291605, 8915.242204412616, 8918.554072296383, 8921.910780892189, 8927.151920567552, 7474.289942455458, 8372.246497815195, 8624.568771561413, 8649.870251946226, 8736.475811451317, 2391.569517486856, 2431.963520547193, 2432.1244352033254, 2432.124435203334, 2433.6714543536664, 2315.8610483371053, 2335.8948847301826, 2372.667826504282, 2378.9643155352683, 2389.928302150938, 2357.3752955466816, 2378.940526486834, 2380.083667787897, 2385.5586716457424, 2386.976130238693, 2313.2135074074813, 2321.8258630602604, 2328.3131476214558, 2328.3597010822073, 2371.039728909794, 2263.5699217148854, 2290.965635515452, 2292.3008482412856, 2292.449812045114, 2295.7704229577075, 8551.914480855236, 8604.655813767442, 8617.052124266325, 8617.267532259766, 8625.482471051695, 5002.424721157041, 5158.277841102211, 5160.92258616121, 5173.456787037345, 5179.300794392784, 3387.7797943133864, 3411.64177761334, 3448.896533104011, 3456.0668619472603, 3481.529993450877, 1145.593314373402, 1161.7095726068483, 1161.8257261410772, 1197.2935967778965, 1198.2870192235694, 12003.879961200364, 12040.21738586989, 12076.577319793765, 12089.57686480969, 12130.27218366892, 4357.9782101089295, 4590.954090459053, 4640.8375706850775, 4745.976270118696, 4748.0978614063215, 10156.796864062677, 10160.0, 10164.0, 10171.84742228867, 10183.0, 5158.690478571298, 5168.508991645787, 5171.043356978971, 5173.663711858792, 5176.430592634807, 4005.663411005278, 4198.265303571863, 4238.724482908663, 4319.552949761802, 4757.35775670287, 3977.6817854572146, 4032.233875563692, 4092.3043082676018, 4097.385392191195, 4111.9177616448105, 1259.1941157659105, 1274.9681257968625, 1280.8783165599252, 1286.8391451068617, 1301.1282440764521, 765.1545042727817, 858.3776761847736, 874.776931882366, 888.5113187746842, 1014.5383850348074, 6044.455998960115, 6175.475084617768, 6202.534809889276, 6206.534509911684, 6538.967305163444, 959.3572306554626, 975.3757595139117, 990.4255531791543, 1007.7631756537207, 1019.1948360794889, 4078.469798926121, 4171.554947605896, 4177.624013838769, 4256.106217694319, 4278.507971583308, 10690.198235132397, 10826.458677066115, 10983.61557345505, 11034.0, 11053.0, 10805.081568066802, 10918.344899306067, 10929.56281748721, 10950.780984380268, 10990.285631433931, 7850.2934735872905, 8160.6327715252955, 8266.628001739937, 8277.875831862528, 8280.668773249123, 6216.658083805434, 6248.250209974832, 6282.434580887742, 6314.4632706218645, 6318.810435686937, 10239.129673977497, 10328.741781455523, 10329.276950613385, 10357.067863892285, 10461.843072353768, 9100.726978190665, 9864.112229899343, 10224.591016359262, 10230.6419275325, 10528.736781580521, 7524.134724506752, 7659.540427574363, 7763.253268014877, 7965.920340796518, 7973.8405231896195, 8297.25324720778, 8350.665973360998, 8356.749297521084, 8438.156184381513, 8517.063123056583, 7660.5403675779635, 7751.922480775345, 7772.222777722182, 7791.4935529190425, 7869.881951770613, 7518.812029699301, 7595.240475952361, 7614.2766437188375, 7646.9617651911385, 7770.53376797394, 7056.717731290896, 7108.324709152622, 7116.750913718202, 7117.110361204849, 7177.856442871218, 5190.299309593157, 5334.759935802976, 5367.597430192673, 5376.059189641719, 5427.5657947363325, 5095.796168153232, 5114.693118412758, 5159.974200128975, 5191.16941289407, 5378.892422151457], "value": 4654.217646936352, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.9808011519311, 321.86964279466815, 329.92246821996827, 334.55838293452655, 335.6190723528794, 5116.565091967187, 5217.973910130453, 5290.497402746738, 5295.947040529593, 5373.32833395826, 5180.948190518094, 5224.268602395668, 5225.686458812474, 5270.183121616155, 5324.0, 4176.958230417687, 4177.644900183488, 4194.203101410738, 4195.916081678364, 4201.873943781676, 3979.462772525701, 4057.797110144496, 4115.9588404115875, 4128.112455821998, 4137.8758637240935, 4093.1199792044704, 4096.467459230311, 4101.384792281152, 4103.958960410388, 4109.095998880257, 4069.5930406959365, 4077.673786097135, 4094.3653733671326, 4102.774347410891, 4117.3618089196225, 3624.4563315502623, 3629.3467175908327, 3633.94549081764, 3647.0, 3648.5986541480415, 5054.671446356012, 5057.519535644108, 5122.0, 5137.948620513822, 5152.252923326097, 4683.320918466803, 4699.0161247607675, 4725.0, 4725.291206319045, 4727.385439892826, 5457.454254574511, 5493.818828951755, 5532.119079512985, 5532.889342213133, 5586.24585680936, 3793.7344385892998, 3956.3274243378405, 3968.1071758854314, 3975.1254723960683, 4010.9972506873282, 5702.201691763156, 5724.656520608777, 5731.914021289684, 5752.769889204426, 5781.277340332501, 4066.7356621819486, 4074.1444296698023, 4078.734882232705, 4086.897827554335, 4112.321466957978, 3889.4554762333296, 4065.715399921977, 4078.938815917764, 4082.8979275517754, 4169.437125988014, 4673.808178914355, 4685.20351540239, 4693.108309421199, 4694.694844835075, 4714.339992401066, 5503.697296648643, 5503.697296648643, 5571.526420254243, 5596.516923015404, 5596.97201513998, 1323.0341850449227, 1352.6821197018692, 1387.7155183187422, 1407.8099456573436, 1413.4063693248654, 913.0869130869131, 942.3780304998805, 956.0344052507018, 957.1146689312358, 958.9760255993656, 5286.233496143038, 5294.338207724035, 5300.469953004668, 5307.548858347007, 5316.7075810830775, 5600.507774040594, 5618.2415373924, 5625.943740562624, 5678.4605462480995, 5692.430756924275, 5177.948220517822, 5289.523942845162, 5338.172583249602, 5361.705106219196, 5392.730363481889, 2611.3341098020264, 2684.2886635041727, 2684.9126103927942, 2697.770689491376, 2711.959320610192, 5192.792288308501, 5213.661112027706, 5258.447862974409, 5260.158374660036, 5262.210668399694, 4782.521747825259, 4999.325091112653, 5055.0, 5068.189089745623, 5166.974165129151, 5109.284700141983, 5127.769335359561, 5138.640295179302, 5139.691618502902, 5167.483251674953, 4998.825041123543, 5007.574356179765, 5026.346574945233, 5026.421961474331, 5032.773525191448, 4781.737004464649, 4891.1685013548495, 4898.828541001048, 4909.631777616764, 4967.950320496749, 5136.152534831711, 5142.948570514247, 5159.458256883124, 5178.948210517847, 5203.401608815035, 3707.5169932026324, 3741.0273328934127, 3749.587545370062, 3791.184895247535, 3802.4486449465226, 4842.232585106482, 5049.873753156054, 5103.392431384103, 5111.437741848468, 5415.945840541544, 5393.325834270716, 5410.2696136022705, 5555.527780138734, 5562.527185189304, 5568.749406276646, 5503.477169668875, 5509.724513774215, 5530.474604912527, 5552.139418390156, 5555.388907220122, 5180.300659410931, 5399.2980912481125, 5448.482394172547, 5501.532369748456, 5531.059719847555, 5532.0, 5558.582561446967, 5564.693941833076, 5569.303837020372, 5569.944300557105, 5441.401445841034, 5454.699991500347, 5469.14049223266, 5470.617056806144, 5492.4507549246355], "value": 4096.882159621923, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.3239850333952, 778.420077042603, 788.5820515126982, 820.4461988157993, 850.2177996243455, 14270.71458570828, 14672.77990830137, 14737.189454579993, 14752.336144873492, 14861.331240094194, 15016.399344026253, 15101.92449037756, 15107.169105699182, 15124.697506049872, 15219.84780152198, 648.2027106658812, 777.8055486128468, 793.6944276453563, 795.8845967334732, 815.2866242038217, 798.1260519730914, 799.9600019998977, 803.7227156630953, 803.9879401808978, 804.1355542791493, 846.2087947768825, 847.0597636623343, 847.5804476784001, 847.5973912391627, 848.1052489623445, 685.1264637587117, 803.9477433966773, 842.7219017724136, 844.4511067805914, 844.9733573707944, 619.2197830733263, 626.8432891777056, 627.0093252660785, 627.6516533324003, 628.4972022382121, 14031.64920876976, 14201.147931124166, 14234.786478202835, 14325.49860754879, 14755.0, 14528.418863245457, 14702.338394772261, 14839.10965342083, 14852.702945941128, 15003.699926001418, 12904.956337734375, 13087.14933529327, 13173.538926137635, 13348.466061357534, 13673.931630341884, 4344.565543445662, 4346.087321662423, 4431.5789999950275, 4563.520830312836, 4569.565891240327, 83618.16381836226, 83784.16215837885, 83882.16117838805, 87411.68882466768, 88963.55518222433, 11883.16817822744, 12010.0, 12064.336461494526, 12083.214591051554, 12245.0, 22484.662730059063, 22542.098316067175, 22543.88728056382, 22588.54822903533, 22621.660675089886, 8588.47550585468, 8636.0, 8672.6530938763, 8858.911410885808, 9051.637934482678, 4838.298446725205, 4911.3369695090705, 4937.629677774181, 5003.224500202475, 5161.019406312788, 3286.0, 3368.781029233141, 3370.6966373026544, 3377.5440315557576, 3382.6955573998457, 599.8440405494515, 604.274870155806, 621.6984762390327, 625.2902955145942, 627.1157667688537, 653.2618141500075, 653.2879161713755, 658.3811217455673, 700.9544379615411, 718.2817182817183, 9445.574949127305, 9502.572384242725, 9516.0, 9759.170470509944, 9824.852627210597, 571.6255852416674, 583.2534356024296, 615.7106160104744, 623.7130919776863, 702.3573430311208, 4554.362389265505, 4671.696339737906, 4715.151272770866, 4755.904881902343, 4804.663673542818, 117788.41105794416, 119826.0, 120569.3402192757, 122600.0, 125382.3730881358, 96281.51859240659, 97458.512707436, 98080.50959745156, 98170.50914745523, 99580.0, 72932.0, 87082.56458717793, 87426.56286718653, 88445.11554884497, 90873.54563227142, 9903.0, 9944.105030547285, 10041.347312424668, 10126.291159618757, 10134.594616215269, 49720.0, 50020.0, 50452.74773626108, 51006.0, 52130.739346303024, 73160.0, 77598.28406005763, 78491.60754196421, 78518.21481785108, 79133.0, 12926.482940682268, 13213.405396756976, 13488.6627834301, 13769.242691652054, 13893.0, 14202.502912398017, 14582.562523124323, 14610.196439195946, 14716.411343546566, 15493.380264789283, 12908.935455322664, 12963.481460741465, 13294.534691285759, 14234.928825355806, 14571.635709107357, 13479.460821567027, 13801.585952421443, 13941.790873136708, 14023.298835058002, 14341.64145896361, 14060.578182654537, 14141.929290353894, 14221.0, 14359.497417590333, 14435.494757683431, 11019.173561982723, 11090.667279981613, 11492.942535287604, 11603.245789023686, 11934.403279836148, 11894.583689570823, 11901.880981190423, 12040.698982525508, 12137.332446715516, 12154.331511766584], "value": 7659.088751644467, "unit": "score"}], "name": "dromaeo_css", "value": 7200.450744353712, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 111.0, 111.0, 112.0, 110.0, 112.0, 111.0, 111.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 120.0, 188.0, 124.0, 120.0, 147.0, 117.0, 159.0, 121.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 160.0, 160.0, 161.0, 163.0, 160.0, 162.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 122.0, 75.0, 75.0, 74.0, 75.0, 112.0, 76.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 120.0, 83.0, 83.0, 84.0, 113.0, 84.0, 84.0, 130.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 63.0, 54.0, 52.0, 53.0, 51.0, 53.0, 54.0, 53.0, 52.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 80.0, 76.0, 75.0, 75.0, 75.0, 75.0, 80.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 134.0, 136.0, 130.0, 119.0, 121.0, 128.0, 120.0, 159.0, 122.0], "value": 129.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 154.0, 160.0, 152.0, 163.0, 155.0, 156.0, 159.0, 161.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 62.0, 63.0, 61.0, 61.0, 65.0, 63.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1486.3999999999999, "alertThreshold": 2.0}]} 18:38:45 INFO - Return code: 0 18:38:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:38:45 INFO - # TBPL SUCCESS # 18:38:45 INFO - Running post-action listener: _resource_record_post_action 18:38:45 INFO - [mozharness: 2016-07-22 01:38:45.161709Z] Finished run-tests step (success) 18:38:45 INFO - Running post-run listener: _resource_record_post_run 18:38:45 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 572887040; Read time: 0; Write time: 486788 18:38:45 INFO - TinderboxPrint: CPU usage
13.1% 18:38:45 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:38:45 INFO - TinderboxPrint: I/O write bytes / time
572,887,040 / 486,788 18:38:45 INFO - TinderboxPrint: CPU idle
8,269.3 (86.9%) 18:38:45 INFO - TinderboxPrint: CPU user
1,199.2 (12.6%) 18:38:45 INFO - TinderboxPrint: Swap in / out
0 / 0 18:38:45 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 273956864; Read time: 0; Write time: 280548 18:38:45 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 298930176; Read time: 0; Write time: 206240 18:38:45 INFO - Running post-run listener: _upload_blobber_files 18:38:45 INFO - Blob upload gear active. 18:38:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:38:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:38:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:38:46 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:38:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:50 INFO - (blobuploader) - INFO - Done attempting. 18:38:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:51 INFO - (blobuploader) - INFO - Done attempting. 18:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:38:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:52 INFO - (blobuploader) - INFO - Done attempting. 18:38:52 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:52 INFO - Return code: 0 18:38:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7afe56bf1f7b082705a4d2309392388b9a75a871cf73ac667adae64e2250d67dae309f2317cf27192bf3ad5a6a4f10a2e08b429fdcf15c994ef04784e0b14c32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac0a6942c8c8fab8fee93546e8ff1fb21bee162782b488c4774827c6a345ffac01ce14500bb8dc992b07cc4739abe9e2f33661c4db128a4841c189897200c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a4ad52856915497fd21b4bb581ef720cfa051324a5f18de0d4eafd7d6ad6e6904a596f197271822693034aebfa4727f13ca49e08668eec4910a5e67bff96f4"} 18:38:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:52 INFO - Contents: 18:38:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7afe56bf1f7b082705a4d2309392388b9a75a871cf73ac667adae64e2250d67dae309f2317cf27192bf3ad5a6a4f10a2e08b429fdcf15c994ef04784e0b14c32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac0a6942c8c8fab8fee93546e8ff1fb21bee162782b488c4774827c6a345ffac01ce14500bb8dc992b07cc4739abe9e2f33661c4db128a4841c189897200c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a4ad52856915497fd21b4bb581ef720cfa051324a5f18de0d4eafd7d6ad6e6904a596f197271822693034aebfa4727f13ca49e08668eec4910a5e67bff96f4"} 18:38:52 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:52 INFO - Copying logs to upload dir... 18:38:52 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.513302 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-21 18:38:51.844676) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 18:38:51.848505) ========= 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=523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7afe56bf1f7b082705a4d2309392388b9a75a871cf73ac667adae64e2250d67dae309f2317cf27192bf3ad5a6a4f10a2e08b429fdcf15c994ef04784e0b14c32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac0a6942c8c8fab8fee93546e8ff1fb21bee162782b488c4774827c6a345ffac01ce14500bb8dc992b07cc4739abe9e2f33661c4db128a4841c189897200c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a4ad52856915497fd21b4bb581ef720cfa051324a5f18de0d4eafd7d6ad6e6904a596f197271822693034aebfa4727f13ca49e08668eec4910a5e67bff96f4"} build_url:https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/AvjISu27QlukmQqG8Pgg5g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7afe56bf1f7b082705a4d2309392388b9a75a871cf73ac667adae64e2250d67dae309f2317cf27192bf3ad5a6a4f10a2e08b429fdcf15c994ef04784e0b14c32", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac0a6942c8c8fab8fee93546e8ff1fb21bee162782b488c4774827c6a345ffac01ce14500bb8dc992b07cc4739abe9e2f33661c4db128a4841c189897200c6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87a4ad52856915497fd21b4bb581ef720cfa051324a5f18de0d4eafd7d6ad6e6904a596f197271822693034aebfa4727f13ca49e08668eec4910a5e67bff96f4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 18:38:51.874469) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:38:51.874864) ========= 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=523ca41ca1e0623de333ad52000001bf-1469149172.550120-545880634 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:38:51.957564) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 18:38:51.957951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 18:38:51.958348) ========= ========= Total master_lag: 0.17 =========