builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1468275415.8 results: success (0) buildid: 20160711135626 builduid: 2fd8c8a9b32d40b194e87be1e87c637a revision: 7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.797275) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.797802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.798176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.813366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.813677) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 15:16:56-- 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% 223M=0s 2016-07-11 15:16:56 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099228 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.927956) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.928412) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014688 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.979968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:55.980389) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e --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 7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e --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=20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 15:16:56,758 truncating revision to first 12 chars 2016-07-11 15:16:56,758 Setting DEBUG logging. 2016-07-11 15:16:56,758 attempt 1/10 2016-07-11 15:16:56,758 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7eeec8b44848?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 15:16:57,068 unpacking tar archive at: mozilla-central-7eeec8b44848/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626377 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:56.621224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:56.621727) ========= script_repo_revision: 7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:56.622414) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:56.622782) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 15:16:56.633780) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-11 15:16:56.634077) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:16:57 INFO - MultiFileLogger online at 20160711 15:16:57 in /builds/slave/test 15:16:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:16:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:16:57 INFO - {'append_to_log': False, 15:16:57 INFO - 'base_work_dir': '/builds/slave/test', 15:16:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:16:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:16:57 INFO - 'branch': 'Firefox-Non-PGO', 15:16:57 INFO - 'buildbot_json_path': 'buildprops.json', 15:16:57 INFO - 'config_files': ('talos/linux_config.py',), 15:16:57 INFO - 'default_actions': ('clobber', 15:16:57 INFO - 'read-buildbot-config', 15:16:57 INFO - 'download-and-extract', 15:16:57 INFO - 'populate-webroot', 15:16:57 INFO - 'create-virtualenv', 15:16:57 INFO - 'install', 15:16:57 INFO - 'run-tests'), 15:16:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:16:57 INFO - 'download_minidump_stackwalk': True, 15:16:57 INFO - 'download_symbols': 'ondemand', 15:16:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:16:57 INFO - 'tooltool.py': '/tools/tooltool.py', 15:16:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:16:57 INFO - '/tools/misc-python/virtualenv.py')}, 15:16:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:16:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:16:57 INFO - 'installer_path': 'installer.exe', 15:16:57 INFO - 'log_level': 'info', 15:16:57 INFO - 'log_name': 'talos', 15:16:57 INFO - 'log_to_console': True, 15:16:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:16:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:16:57 INFO - 'opt_config_files': (), 15:16:57 INFO - 'pip_index': False, 15:16:57 INFO - 'sps_profile': False, 15:16:57 INFO - 'sps_profile_interval': 0, 15:16:57 INFO - 'suite': 'dromaeojs', 15:16:57 INFO - 'system_bits': '32', 15:16:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:16:57 INFO - 'title': 'talos-linux64-ix-015', 15:16:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:16:57 INFO - 'use_talos_json': True, 15:16:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:16:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:16:57 INFO - 'work_dir': 'build'} 15:16:57 INFO - ##### 15:16:57 INFO - ##### Running clobber step. 15:16:57 INFO - ##### 15:16:57 INFO - Running pre-action listener: _resource_record_pre_action 15:16:57 INFO - Running main action method: clobber 15:16:57 INFO - rmtree: /builds/slave/test/build 15:16:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:16:58 INFO - Running post-action listener: _resource_record_post_action 15:16:58 INFO - ##### 15:16:58 INFO - ##### Finished clobber step (success) 15:16:58 INFO - ##### 15:16:58 INFO - ##### 15:16:58 INFO - ##### Running read-buildbot-config step. 15:16:58 INFO - ##### 15:16:58 INFO - Running pre-action listener: _resource_record_pre_action 15:16:58 INFO - Running main action method: read_buildbot_config 15:16:58 INFO - Using buildbot properties: 15:16:58 INFO - { 15:16:58 INFO - "project": "", 15:16:58 INFO - "product": "firefox", 15:16:58 INFO - "script_repo_revision": "production", 15:16:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:16:58 INFO - "repository": "", 15:16:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:16:58 INFO - "buildid": "20160711135626", 15:16:58 INFO - "pgo_build": "False", 15:16:58 INFO - "basedir": "/builds/slave/test", 15:16:58 INFO - "buildnumber": 647, 15:16:58 INFO - "slavename": "talos-linux64-ix-015", 15:16:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:16:58 INFO - "platform": "ubuntu64_hw", 15:16:58 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:16:58 INFO - "branch": "mozilla-central", 15:16:58 INFO - "repo_path": "mozilla-central", 15:16:58 INFO - "revision": "7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e", 15:16:58 INFO - "stage_platform": "linux64", 15:16:58 INFO - "builduid": "2fd8c8a9b32d40b194e87be1e87c637a", 15:16:58 INFO - "slavebuilddir": "test" 15:16:58 INFO - } 15:16:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:16:58 INFO - Running post-action listener: _resource_record_post_action 15:16:58 INFO - ##### 15:16:58 INFO - ##### Finished read-buildbot-config step (success) 15:16:58 INFO - ##### 15:16:58 INFO - ##### 15:16:58 INFO - ##### Running download-and-extract step. 15:16:58 INFO - ##### 15:16:58 INFO - Running pre-action listener: _resource_record_pre_action 15:16:58 INFO - Running main action method: download_and_extract 15:16:58 INFO - mkdir: /builds/slave/test/build/tests 15:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:58 INFO - https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:16:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:16:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:16:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:16:58 INFO - Downloaded 1361 bytes. 15:16:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:16:58 INFO - Using the following test package requirements: 15:16:58 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:58 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:16:58 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:16:58 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'jsshell-linux-x86_64.zip'], 15:16:58 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:16:58 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:58 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:16:58 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:16:58 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:16:58 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:16:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:58 INFO - https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:16:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:16:59 INFO - Downloaded 22737688 bytes. 15:16:59 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'] 15:16:59 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 15:17:00 INFO - Return code: 0 15:17:00 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 15:17:00 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')]} 15:17:00 INFO - https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:17:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:17:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:17:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:17:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:17:02 INFO - Downloaded 22737688 bytes. 15:17:02 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'] 15:17:02 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 15:17:03 INFO - Return code: 0 15:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:03 INFO - https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:17:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:17:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:17:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:17:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:17:04 INFO - Downloaded 14403333 bytes. 15:17:04 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'] 15:17:04 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 15:17:04 INFO - Return code: 0 15:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:04 INFO - https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:17:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:17:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:17:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:17:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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 15:17:06 INFO - Downloaded 56650423 bytes. 15:17:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:17:06 INFO - mkdir: /builds/slave/test/properties 15:17:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:17:06 INFO - Writing to file /builds/slave/test/properties/build_url 15:17:06 INFO - Contents: 15:17:06 INFO - build_url:https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:17:06 INFO - Running post-action listener: _resource_record_post_action 15:17:06 INFO - Running post-action listener: set_extra_try_arguments 15:17:06 INFO - ##### 15:17:06 INFO - ##### Finished download-and-extract step (success) 15:17:06 INFO - ##### 15:17:06 INFO - ##### 15:17:06 INFO - ##### Running populate-webroot step. 15:17:06 INFO - ##### 15:17:06 INFO - Running pre-action listener: _resource_record_pre_action 15:17:06 INFO - Running main action method: populate_webroot 15:17:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:17:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:17:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:17:06 INFO - '--tppagecycles', 15:17:06 INFO - '7'], 15:17:06 INFO - 'tests': ['tsvgm']}}, 15:17:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:17:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:17:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:17:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:17:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:17:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:17:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['damp', 'tps']}, 15:17:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'tests': ['damp', 'tps']}, 15:17:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['dromaeo_dom']}, 15:17:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:17:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['basic_compositor_video']}, 15:17:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:17:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['a11yr', 15:17:06 INFO - 'ts_paint', 15:17:06 INFO - 'tpaint', 15:17:06 INFO - 'sessionrestore', 15:17:06 INFO - 'sessionrestore_no_auto_restore', 15:17:06 INFO - 'tabpaint']}, 15:17:06 INFO - 'other-e10s': {'tests': ['a11yr', 15:17:06 INFO - 'ts_paint', 15:17:06 INFO - 'tpaint', 15:17:06 INFO - 'sessionrestore', 15:17:06 INFO - 'sessionrestore_no_auto_restore', 15:17:06 INFO - 'tabpaint']}, 15:17:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:17:06 INFO - 'ts_paint', 15:17:06 INFO - 'tpaint', 15:17:06 INFO - 'sessionrestore', 15:17:06 INFO - 'sessionrestore_no_auto_restore', 15:17:06 INFO - 'tabpaint']}, 15:17:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:17:06 INFO - 'ts_paint', 15:17:06 INFO - 'tpaint', 15:17:06 INFO - 'sessionrestore', 15:17:06 INFO - 'sessionrestore_no_auto_restore', 15:17:06 INFO - 'tabpaint']}, 15:17:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['a11yr', 15:17:06 INFO - 'ts_paint', 15:17:06 INFO - 'tpaint', 15:17:06 INFO - 'sessionrestore', 15:17:06 INFO - 'sessionrestore_no_auto_restore', 15:17:06 INFO - 'tabpaint']}, 15:17:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['a11yr', 15:17:06 INFO - 'ts_paint', 15:17:06 INFO - 'tpaint', 15:17:06 INFO - 'sessionrestore', 15:17:06 INFO - 'sessionrestore_no_auto_restore', 15:17:06 INFO - 'tabpaint']}, 15:17:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['tsvgx', 15:17:06 INFO - 'tsvgr_opacity', 15:17:06 INFO - 'tart', 15:17:06 INFO - 'tscrollx', 15:17:06 INFO - 'cart']}, 15:17:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:17:06 INFO - 'tsvgr_opacity', 15:17:06 INFO - 'tart', 15:17:06 INFO - 'tscrollx', 15:17:06 INFO - 'cart']}, 15:17:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'talos_options': ['--disable-e10s'], 15:17:06 INFO - 'tests': ['tp5o']}, 15:17:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'tests': ['tp5o']}, 15:17:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'talos_options': ['--disable-e10s', 15:17:06 INFO - '--xperf_path', 15:17:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:17:06 INFO - 'tests': ['tp5n']}, 15:17:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:06 INFO - 'talos_options': ['--xperf_path', 15:17:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:17:06 INFO - 'tests': ['tp5n']}}, 15:17:06 INFO - 'talos.zip': {'path': '', 15:17:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:17:06 INFO - Running post-action listener: _resource_record_post_action 15:17:06 INFO - ##### 15:17:06 INFO - ##### Finished populate-webroot step (success) 15:17:06 INFO - ##### 15:17:06 INFO - ##### 15:17:06 INFO - ##### Running create-virtualenv step. 15:17:06 INFO - ##### 15:17:06 INFO - Running pre-action listener: _resource_record_pre_action 15:17:06 INFO - Running main action method: create_virtualenv 15:17:06 INFO - Creating virtualenv /builds/slave/test/build/venv 15:17:06 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 15:17:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:17:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:17:07 INFO - Using real prefix '/usr' 15:17:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:17:07 INFO - Installing distribute.............................................................................................................................................................................................done. 15:17:09 INFO - Installing pip.................done. 15:17:09 INFO - Return code: 0 15:17:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:17:09 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')]} 15:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:09 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')]} 15:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:09 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:09 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 15:17:09 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 15:17:09 INFO - Using env: {'DISPLAY': ':0', 15:17:09 INFO - 'HOME': '/home/cltbld', 15:17:09 INFO - 'LANG': 'en_US.UTF-8', 15:17:09 INFO - 'LANGUAGE': 'en_US:en', 15:17:09 INFO - 'LOGNAME': 'cltbld', 15:17:09 INFO - 'MAIL': '/var/mail/cltbld', 15:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:09 INFO - 'MOZ_NO_REMOTE': '1', 15:17:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:09 INFO - 'NO_EM_RESTART': '1', 15:17:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:09 INFO - 'PWD': '/builds/slave/test', 15:17:09 INFO - 'SHELL': '/bin/bash', 15:17:09 INFO - 'SHLVL': '1', 15:17:09 INFO - 'TERM': 'linux', 15:17:09 INFO - 'TMOUT': '86400', 15:17:09 INFO - 'USER': 'cltbld', 15:17:09 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:09 INFO - '_': '/tools/buildbot/bin/python'} 15:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:09 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 15:17:09 INFO - Cleaning up... 15:17:09 INFO - Return code: 0 15:17:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:17:09 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')]} 15:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:09 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')]} 15:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:09 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:09 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 15:17:09 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 15:17:09 INFO - Using env: {'DISPLAY': ':0', 15:17:09 INFO - 'HOME': '/home/cltbld', 15:17:09 INFO - 'LANG': 'en_US.UTF-8', 15:17:09 INFO - 'LANGUAGE': 'en_US:en', 15:17:09 INFO - 'LOGNAME': 'cltbld', 15:17:09 INFO - 'MAIL': '/var/mail/cltbld', 15:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:09 INFO - 'MOZ_NO_REMOTE': '1', 15:17:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:09 INFO - 'NO_EM_RESTART': '1', 15:17:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:09 INFO - 'PWD': '/builds/slave/test', 15:17:09 INFO - 'SHELL': '/bin/bash', 15:17:09 INFO - 'SHLVL': '1', 15:17:09 INFO - 'TERM': 'linux', 15:17:09 INFO - 'TMOUT': '86400', 15:17:09 INFO - 'USER': 'cltbld', 15:17:09 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:09 INFO - '_': '/tools/buildbot/bin/python'} 15:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:10 INFO - Downloading/unpacking psutil>=3.1.1 15:17:10 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 15:17:10 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 15:17:10 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 15:17:10 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 15:17:10 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 15:17:10 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 15:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:17:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:17:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:17:12 INFO - Installing collected packages: psutil 15:17:12 INFO - Running setup.py install for psutil 15:17:12 INFO - building 'psutil._psutil_linux' extension 15:17:12 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 15:17:12 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 15:17:12 INFO - building 'psutil._psutil_posix' extension 15:17:12 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 15:17:12 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 15:17:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:17:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:17:12 INFO - Successfully installed psutil 15:17:12 INFO - Cleaning up... 15:17:12 INFO - Return code: 0 15:17:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:17:12 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')]} 15:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:12 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')]} 15:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:12 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:12 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 15:17:12 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 15:17:12 INFO - Using env: {'DISPLAY': ':0', 15:17:12 INFO - 'HOME': '/home/cltbld', 15:17:12 INFO - 'LANG': 'en_US.UTF-8', 15:17:12 INFO - 'LANGUAGE': 'en_US:en', 15:17:12 INFO - 'LOGNAME': 'cltbld', 15:17:12 INFO - 'MAIL': '/var/mail/cltbld', 15:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:12 INFO - 'MOZ_NO_REMOTE': '1', 15:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:12 INFO - 'NO_EM_RESTART': '1', 15:17:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:12 INFO - 'PWD': '/builds/slave/test', 15:17:12 INFO - 'SHELL': '/bin/bash', 15:17:12 INFO - 'SHLVL': '1', 15:17:12 INFO - 'TERM': 'linux', 15:17:12 INFO - 'TMOUT': '86400', 15:17:12 INFO - 'USER': 'cltbld', 15:17:12 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:12 INFO - '_': '/tools/buildbot/bin/python'} 15:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:17:12 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 15:17:12 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 15:17:12 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 15:17:12 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 15:17:12 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 15:17:12 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 15:17:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:17:14 INFO - Installing collected packages: mozsystemmonitor 15:17:14 INFO - Running setup.py install for mozsystemmonitor 15:17:14 INFO - Successfully installed mozsystemmonitor 15:17:14 INFO - Cleaning up... 15:17:14 INFO - Return code: 0 15:17:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:14 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:14 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 15:17:14 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 15:17:14 INFO - Using env: {'DISPLAY': ':0', 15:17:14 INFO - 'HOME': '/home/cltbld', 15:17:14 INFO - 'LANG': 'en_US.UTF-8', 15:17:14 INFO - 'LANGUAGE': 'en_US:en', 15:17:14 INFO - 'LOGNAME': 'cltbld', 15:17:14 INFO - 'MAIL': '/var/mail/cltbld', 15:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:14 INFO - 'MOZ_NO_REMOTE': '1', 15:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:14 INFO - 'NO_EM_RESTART': '1', 15:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:14 INFO - 'PWD': '/builds/slave/test', 15:17:14 INFO - 'SHELL': '/bin/bash', 15:17:14 INFO - 'SHLVL': '1', 15:17:14 INFO - 'TERM': 'linux', 15:17:14 INFO - 'TMOUT': '86400', 15:17:14 INFO - 'USER': 'cltbld', 15:17:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:14 INFO - '_': '/tools/buildbot/bin/python'} 15:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:15 INFO - Downloading/unpacking jsonschema==2.5.1 15:17:15 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 15:17:15 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 15:17:15 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 15:17:15 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 15:17:15 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 15:17:15 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 15:17:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:17:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 INFO - Downloading functools32-3.2.3-2.tar.gz 15:17:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:17:17 INFO - warning: no files found matching '*.txt' 15:17:17 INFO - no previously-included directories found matching 'build' 15:17:17 INFO - no previously-included directories found matching 'dist' 15:17:17 INFO - no previously-included directories found matching '.git*' 15:17:17 INFO - Installing collected packages: jsonschema, functools32 15:17:17 INFO - Running setup.py install for functools32 15:17:17 INFO - warning: no files found matching '*.txt' 15:17:17 INFO - no previously-included directories found matching 'build' 15:17:17 INFO - no previously-included directories found matching 'dist' 15:17:17 INFO - no previously-included directories found matching '.git*' 15:17:17 INFO - Successfully installed jsonschema functools32 15:17:17 INFO - Cleaning up... 15:17:17 INFO - Return code: 0 15:17:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:17:17 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')]} 15:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:17 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')]} 15:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:17 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:17 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 15:17:17 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 15:17:17 INFO - Using env: {'DISPLAY': ':0', 15:17:17 INFO - 'HOME': '/home/cltbld', 15:17:17 INFO - 'LANG': 'en_US.UTF-8', 15:17:17 INFO - 'LANGUAGE': 'en_US:en', 15:17:17 INFO - 'LOGNAME': 'cltbld', 15:17:17 INFO - 'MAIL': '/var/mail/cltbld', 15:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:17 INFO - 'MOZ_NO_REMOTE': '1', 15:17:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:17 INFO - 'NO_EM_RESTART': '1', 15:17:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:17 INFO - 'PWD': '/builds/slave/test', 15:17:17 INFO - 'SHELL': '/bin/bash', 15:17:17 INFO - 'SHLVL': '1', 15:17:17 INFO - 'TERM': 'linux', 15:17:17 INFO - 'TMOUT': '86400', 15:17:17 INFO - 'USER': 'cltbld', 15:17:17 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:17 INFO - '_': '/tools/buildbot/bin/python'} 15:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 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 15:17:17 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 15:17:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:17:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:17:19 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 15:17:19 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 15:17:19 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 15:17:19 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 15:17:19 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 15:17:19 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 15:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:17:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:17:20 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 15:17:20 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 15:17:20 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 15:17:20 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 15:17:20 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 15:17:20 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 15:17:20 INFO - Downloading docopt-0.6.1.tar.gz 15:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:17:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:17:20 INFO - Running setup.py install for blobuploader 15:17:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:17:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:17:20 INFO - Running setup.py install for requests 15:17:20 INFO - Running setup.py install for docopt 15:17:21 INFO - Successfully installed blobuploader requests docopt 15:17:21 INFO - Cleaning up... 15:17:21 INFO - Return code: 0 15:17:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:21 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:21 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 15:17:21 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 15:17:21 INFO - Using env: {'DISPLAY': ':0', 15:17:21 INFO - 'HOME': '/home/cltbld', 15:17:21 INFO - 'LANG': 'en_US.UTF-8', 15:17:21 INFO - 'LANGUAGE': 'en_US:en', 15:17:21 INFO - 'LOGNAME': 'cltbld', 15:17:21 INFO - 'MAIL': '/var/mail/cltbld', 15:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:21 INFO - 'MOZ_NO_REMOTE': '1', 15:17:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:21 INFO - 'NO_EM_RESTART': '1', 15:17:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:21 INFO - 'PWD': '/builds/slave/test', 15:17:21 INFO - 'SHELL': '/bin/bash', 15:17:21 INFO - 'SHLVL': '1', 15:17:21 INFO - 'TERM': 'linux', 15:17:21 INFO - 'TMOUT': '86400', 15:17:21 INFO - 'USER': 'cltbld', 15:17:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:21 INFO - '_': '/tools/buildbot/bin/python'} 15:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:21 INFO - Running setup.py (path:/tmp/pip-TxTvvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:21 INFO - Running setup.py (path:/tmp/pip-s3yp5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:21 INFO - Running setup.py (path:/tmp/pip-IYCQp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:21 INFO - Running setup.py (path:/tmp/pip-Pxv9FU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:21 INFO - Running setup.py (path:/tmp/pip-T6AovO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:21 INFO - Running setup.py (path:/tmp/pip-sxdjPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:21 INFO - Running setup.py (path:/tmp/pip-6Og3HL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:21 INFO - Running setup.py (path:/tmp/pip-qtqadW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:21 INFO - Running setup.py (path:/tmp/pip-h7W8lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:21 INFO - Running setup.py (path:/tmp/pip-Y6IVuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:22 INFO - Running setup.py (path:/tmp/pip-3zra_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:22 INFO - Running setup.py (path:/tmp/pip-_CDjyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:22 INFO - Running setup.py (path:/tmp/pip-WADH7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:22 INFO - Running setup.py (path:/tmp/pip-KrzCCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:22 INFO - Running setup.py (path:/tmp/pip-Exf6f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:22 INFO - Running setup.py (path:/tmp/pip-_DWrLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:22 INFO - Running setup.py (path:/tmp/pip-sdtl_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:17:22 INFO - Running setup.py install for manifestparser 15:17:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:17:22 INFO - Running setup.py install for mozcrash 15:17:22 INFO - Running setup.py install for mozdebug 15:17:22 INFO - Running setup.py install for mozdevice 15:17:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:17:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:17:22 INFO - Running setup.py install for mozfile 15:17:23 INFO - Running setup.py install for mozhttpd 15:17:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Running setup.py install for mozinfo 15:17:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Running setup.py install for mozInstall 15:17:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Running setup.py install for mozleak 15:17:23 INFO - Running setup.py install for mozlog 15:17:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Running setup.py install for moznetwork 15:17:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Running setup.py install for mozprocess 15:17:23 INFO - Running setup.py install for mozprofile 15:17:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:17:23 INFO - Running setup.py install for mozrunner 15:17:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:17:24 INFO - Running setup.py install for mozscreenshot 15:17:24 INFO - Running setup.py install for moztest 15:17:24 INFO - Running setup.py install for mozversion 15:17:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:17:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:17:24 INFO - Cleaning up... 15:17:24 INFO - Return code: 0 15:17:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:24 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:24 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 15:17:24 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 15:17:24 INFO - Using env: {'DISPLAY': ':0', 15:17:24 INFO - 'HOME': '/home/cltbld', 15:17:24 INFO - 'LANG': 'en_US.UTF-8', 15:17:24 INFO - 'LANGUAGE': 'en_US:en', 15:17:24 INFO - 'LOGNAME': 'cltbld', 15:17:24 INFO - 'MAIL': '/var/mail/cltbld', 15:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:24 INFO - 'MOZ_NO_REMOTE': '1', 15:17:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:24 INFO - 'NO_EM_RESTART': '1', 15:17:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:24 INFO - 'PWD': '/builds/slave/test', 15:17:24 INFO - 'SHELL': '/bin/bash', 15:17:24 INFO - 'SHLVL': '1', 15:17:24 INFO - 'TERM': 'linux', 15:17:24 INFO - 'TMOUT': '86400', 15:17:24 INFO - 'USER': 'cltbld', 15:17:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:24 INFO - '_': '/tools/buildbot/bin/python'} 15:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:24 INFO - Running setup.py (path:/tmp/pip-fnX4fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:24 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)) 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:24 INFO - Running setup.py (path:/tmp/pip-GSjIul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:24 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)) 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:24 INFO - Running setup.py (path:/tmp/pip-wyOSqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:24 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)) 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:24 INFO - Running setup.py (path:/tmp/pip-ZyWK80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:24 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)) 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:24 INFO - Running setup.py (path:/tmp/pip-svVD_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:24 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)) 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:24 INFO - Running setup.py (path:/tmp/pip-gb7ouH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:24 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)) 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:24 INFO - Running setup.py (path:/tmp/pip-kAMOPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:25 INFO - Running setup.py (path:/tmp/pip-jGBRk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:25 INFO - Running setup.py (path:/tmp/pip-IeviYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:25 INFO - Running setup.py (path:/tmp/pip-cfZG7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:25 INFO - Running setup.py (path:/tmp/pip-vJClYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:25 INFO - Running setup.py (path:/tmp/pip-CmYtRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:25 INFO - Running setup.py (path:/tmp/pip-CsMY8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:25 INFO - Running setup.py (path:/tmp/pip-UKrNt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:25 INFO - Running setup.py (path:/tmp/pip-PRlxBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:25 INFO - Running setup.py (path:/tmp/pip-9gTsrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:25 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)) 15:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:25 INFO - Running setup.py (path:/tmp/pip-1BXN_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:25 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)) 15:17:25 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)) 15:17:25 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)) 15:17:25 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)) 15:17:25 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)) 15:17:25 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)) 15:17:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:17:25 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 15:17:25 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 15:17:25 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 15:17:25 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 15:17:25 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 15:17:25 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 15:17:27 INFO - Downloading blessings-1.6.tar.gz 15:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:17:27 INFO - Installing collected packages: blessings 15:17:27 INFO - Running setup.py install for blessings 15:17:27 INFO - Successfully installed blessings 15:17:27 INFO - Cleaning up... 15:17:27 INFO - Return code: 0 15:17:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:17:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:28 INFO - Reading from file tmpfile_stdout 15:17:28 INFO - Current package versions: 15:17:28 INFO - argparse == 1.2.1 15:17:28 INFO - blessings == 1.6 15:17:28 INFO - blobuploader == 1.2.4 15:17:28 INFO - docopt == 0.6.1 15:17:28 INFO - functools32 == 3.2.3-2 15:17:28 INFO - jsonschema == 2.5.1 15:17:28 INFO - manifestparser == 1.1 15:17:28 INFO - mozInstall == 1.12 15:17:28 INFO - mozcrash == 0.17 15:17:28 INFO - mozdebug == 0.1 15:17:28 INFO - mozdevice == 0.48 15:17:28 INFO - mozfile == 1.2 15:17:28 INFO - mozhttpd == 0.7 15:17:28 INFO - mozinfo == 0.9 15:17:28 INFO - mozleak == 0.1 15:17:28 INFO - mozlog == 3.2 15:17:28 INFO - moznetwork == 0.27 15:17:28 INFO - mozprocess == 0.23 15:17:28 INFO - mozprofile == 0.28 15:17:28 INFO - mozrunner == 6.12 15:17:28 INFO - mozscreenshot == 0.1 15:17:28 INFO - mozsystemmonitor == 0.3 15:17:28 INFO - moztest == 0.7 15:17:28 INFO - mozversion == 1.4 15:17:28 INFO - psutil == 3.1.1 15:17:28 INFO - requests == 1.2.3 15:17:28 INFO - wsgiref == 0.1.2 15:17:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:28 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 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:28 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 15:17:28 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 15:17:28 INFO - Using env: {'DISPLAY': ':0', 15:17:28 INFO - 'HOME': '/home/cltbld', 15:17:28 INFO - 'LANG': 'en_US.UTF-8', 15:17:28 INFO - 'LANGUAGE': 'en_US:en', 15:17:28 INFO - 'LOGNAME': 'cltbld', 15:17:28 INFO - 'MAIL': '/var/mail/cltbld', 15:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:28 INFO - 'MOZ_NO_REMOTE': '1', 15:17:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:28 INFO - 'NO_EM_RESTART': '1', 15:17:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:28 INFO - 'PWD': '/builds/slave/test', 15:17:28 INFO - 'SHELL': '/bin/bash', 15:17:28 INFO - 'SHLVL': '1', 15:17:28 INFO - 'TERM': 'linux', 15:17:28 INFO - 'TMOUT': '86400', 15:17:28 INFO - 'USER': 'cltbld', 15:17:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:28 INFO - '_': '/tools/buildbot/bin/python'} 15:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 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)) 15:17:28 INFO - Cleaning up... 15:17:28 INFO - Return code: 0 15:17:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xde5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, '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': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', '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 15:17:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:17:28 INFO - Using env: {'DISPLAY': ':0', 15:17:28 INFO - 'HOME': '/home/cltbld', 15:17:28 INFO - 'LANG': 'en_US.UTF-8', 15:17:28 INFO - 'LANGUAGE': 'en_US:en', 15:17:28 INFO - 'LOGNAME': 'cltbld', 15:17:28 INFO - 'MAIL': '/var/mail/cltbld', 15:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:28 INFO - 'MOZ_NO_REMOTE': '1', 15:17:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:28 INFO - 'NO_EM_RESTART': '1', 15:17:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:28 INFO - 'PWD': '/builds/slave/test', 15:17:28 INFO - 'SHELL': '/bin/bash', 15:17:28 INFO - 'SHLVL': '1', 15:17:28 INFO - 'TERM': 'linux', 15:17:28 INFO - 'TMOUT': '86400', 15:17:28 INFO - 'USER': 'cltbld', 15:17:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:28 INFO - '_': '/tools/buildbot/bin/python'} 15:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:17:28 INFO - Cleaning up... 15:17:28 INFO - Return code: 0 15:17:28 INFO - Running post-action listener: _resource_record_post_action 15:17:28 INFO - Running post-action listener: _start_resource_monitoring 15:17:28 INFO - Starting resource monitoring. 15:17:28 INFO - ##### 15:17:28 INFO - ##### Finished create-virtualenv step (success) 15:17:28 INFO - ##### 15:17:28 INFO - ##### 15:17:28 INFO - ##### Running install step. 15:17:28 INFO - ##### 15:17:28 INFO - Running pre-action listener: _resource_record_pre_action 15:17:28 INFO - Running main action method: install 15:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:28 INFO - Reading from file tmpfile_stdout 15:17:28 INFO - Detecting whether we're running mozinstall >=1.0... 15:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:17:28 INFO - Reading from file tmpfile_stdout 15:17:28 INFO - Output received: 15:17:28 INFO - Usage: mozinstall [options] installer 15:17:28 INFO - Options: 15:17:28 INFO - -h, --help show this help message and exit 15:17:28 INFO - -d DEST, --destination=DEST 15:17:28 INFO - Directory to install application into. [default: 15:17:28 INFO - "/builds/slave/test"] 15:17:28 INFO - --app=APP Application being installed. [default: firefox] 15:17:28 INFO - mkdir: /builds/slave/test/build/application 15:17:28 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'] 15:17:28 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 15:17:45 INFO - Reading from file tmpfile_stdout 15:17:45 INFO - Output received: 15:17:45 INFO - /builds/slave/test/build/application/firefox/firefox 15:17:45 INFO - Running post-action listener: _resource_record_post_action 15:17:45 INFO - ##### 15:17:45 INFO - ##### Finished install step (success) 15:17:45 INFO - ##### 15:17:45 INFO - ##### 15:17:45 INFO - ##### Running run-tests step. 15:17:45 INFO - ##### 15:17:45 INFO - Running pre-action listener: _resource_record_pre_action 15:17:45 INFO - Running main action method: run_tests 15:17:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:45 INFO - Python 2.7.3 15:17:45 INFO - Return code: 0 15:17:45 INFO - grabbing minidump binary from tooltool 15:17: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')]} 15:17:45 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 0x10a43b0>, '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 0x10a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17:45 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 15:17:45 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 15:17:45 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 15:17:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:45 INFO - Return code: 0 15:17:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:17:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:17:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 15:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 15:17:45 INFO - Using env: {'DISPLAY': ':0', 15:17:45 INFO - 'HOME': '/home/cltbld', 15:17:45 INFO - 'LANG': 'en_US.UTF-8', 15:17:45 INFO - 'LANGUAGE': 'en_US:en', 15:17:45 INFO - 'LOGNAME': 'cltbld', 15:17:45 INFO - 'MAIL': '/var/mail/cltbld', 15:17:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:45 INFO - 'MOZ_NO_REMOTE': '1', 15:17:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:45 INFO - 'NO_EM_RESTART': '1', 15:17:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:45 INFO - 'PWD': '/builds/slave/test', 15:17:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:45 INFO - 'SHELL': '/bin/bash', 15:17:45 INFO - 'SHLVL': '1', 15:17:45 INFO - 'TERM': 'linux', 15:17:45 INFO - 'TMOUT': '86400', 15:17:45 INFO - 'USER': 'cltbld', 15:17:45 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654', 15:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:45 INFO - '_': '/tools/buildbot/bin/python'} 15:17:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 15:17:45 INFO - mozversion application_buildid: 20160711135626 15:17:45 INFO - mozversion application_changeset: 7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e 15:17:45 INFO - mozversion application_display_name: Nightly 15:17:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:45 INFO - mozversion application_name: Firefox 15:17:45 INFO - mozversion application_remotingname: firefox 15:17:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:17:45 INFO - mozversion application_vendor: Mozilla 15:17:45 INFO - mozversion application_version: 50.0a1 15:17:45 INFO - mozversion platform_buildid: 20160711135626 15:17:45 INFO - mozversion platform_changeset: 7eeec8b448489d9fc6ac6c11a01b7ba78ee56f9e 15:17:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:17:45 INFO - mozversion platform_version: 50.0a1 15:17:45 INFO - using testdate: 1468275465 15:17:45 INFO - actual date: 1468275465 15:17:45 INFO - starting webserver on 'localhost:32945' 15:17:45 INFO - SUITE-START | Running 2 tests 15:17:45 INFO - TEST-START | dromaeo_css 15:17:45 INFO - operating with platform_type : linux_ 15:17:45 INFO - Initialising browser for dromaeo_css test... 15:17:45 INFO - TEST-INFO | started process 22595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwHW2PC/profile http://localhost:32945/getInfo.html) 15:17:49 INFO - PROCESS | 22595 | __metrics Screen width/height:1600/1200 15:17:49 INFO - PROCESS | 22595 | colorDepth:24 15:17:49 INFO - PROCESS | 22595 | Browser inner width/height: 1024/697 15:17:49 INFO - PROCESS | 22595 | __metrics 15:17:50 INFO - PROCESS | 22595 | 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" 15:17:50 INFO - PROCESS | 22595 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:17:51 INFO - TEST-INFO | 22595: exit 0 15:17:51 INFO - Browser initialized. 15:17:51 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:51 INFO - TEST-INFO | started process 22676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwHW2PC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:03 INFO - PROCESS | 22676 | RSS: Main: 159047680 15:18:03 INFO - PROCESS | 22676 | 15:18:55 INFO - PROCESS | 22676 | 1468275535266 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] 15:21:06 INFO - PROCESS | 22676 | Cycle 1(1): loaded http://localhost:32945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32945/tests/dromaeo/cssquery-ext.html) 15:21:08 INFO - PROCESS | 22676 | RSS: Main: 241709056 15:21:08 INFO - PROCESS | 22676 | 15:24:11 INFO - PROCESS | 22676 | Cycle 1(1): loaded http://localhost:32945/tests/dromaeo/cssquery-ext.html (next: http://localhost:32945/tests/dromaeo/cssquery-jquery.html) 15:24:11 INFO - PROCESS | 22676 | RSS: Main: 243765248 15:24:11 INFO - PROCESS | 22676 | 15:27:13 INFO - PROCESS | 22676 | Cycle 1(1): loaded http://localhost:32945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32945/tests/dromaeo/cssquery-mootools.html) 15:27:14 INFO - PROCESS | 22676 | RSS: Main: 242876416 15:27:14 INFO - PROCESS | 22676 | 15:30:16 INFO - PROCESS | 22676 | Cycle 1(1): loaded http://localhost:32945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32945/tests/dromaeo/cssquery-prototype.html) 15:30:17 INFO - PROCESS | 22676 | RSS: Main: 244846592 15:30:17 INFO - PROCESS | 22676 | 15:33:20 INFO - PROCESS | 22676 | Cycle 1(1): loaded http://localhost:32945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32945/tests/dromaeo/cssquery-yui.html) 15:33:21 INFO - PROCESS | 22676 | RSS: Main: 221646848 15:33:21 INFO - PROCESS | 22676 | 15:36:23 INFO - PROCESS | 22676 | Cycle 1(1): loaded http://localhost:32945/tests/dromaeo/cssquery-yui.html (next: http://localhost:32945/tests/dromaeo/cssquery-dojo.html) 15:36:24 INFO - PROCESS | 22676 | RSS: Main: 309055488 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | __start_tp_report 15:36:24 INFO - PROCESS | 22676 | _x_x_mozilla_page_load 15:36:24 INFO - PROCESS | 22676 | _x_x_mozilla_page_load_details 15:36:24 INFO - PROCESS | 22676 | |i|pagename|runs| 15:36:24 INFO - PROCESS | 22676 | |0;dojo.html;2636.617690434887;2641.8546979916096;2675.0770984010514;2688.462307538493;2691.5020721166575;4675.088357770236;4727.881802954924;4752.857414277577;4799.928001079987;4850.80596776129;4668.206404911165;4729.361536192613;4736.928946065812;4782.808687652489;4785.880352991177;4110.403991421257;4353.2599458092045;4384.912301753963;4432.645388368939;4521.118381915528;4447.221736196169;4489.236829738952;4502.977485112586;4508.188526065307;4527.275635898257;4306.35404689296;4355.760433176174;4366.519682834885;4414.183376075436;4433.9556604433865;4399.846005389796;4409.691321607489;4436;4486.8878278042985;4500.707454015478;4449.488308844492;4455.198064348416;4469.7318160910445;4490.528494508095;4495.775211239425;4690.413698287714;4731.7634118293945;4818.229083346648;4926.5812405945535;4927.43334516528;5193.610479214036;5243.501867322599;5292.417834038252;5295.443978382292;5296.470352964711;5287.497687719699;5299.046171689094;5305.867353316159;5320.600954928357;5325.307709997675;4665.790039448233;4732.621390288768;4807.038592281558;4911.631627627907;4960.057589058068;5252.2384254282915;5271.710055946883;5275.551578115903;5320.308359913264;5335.653182543122;5055;5086.465921078234;5097.490250974873;5100.948990510122;5109.105906466428;4990.525900039491;5125.077486052546;5137.743112844343;5226.764795584208;5240.816571419982;5192.61055420845;5196.8960620787375;5219.321488206585;5221.660592061504;5225.346831646044;5186.118359878829;5202.3497062867145;5202.81790137351;5204.479552044765;5239.659422137549;4293.892652683708;4336.696431249782;4341.110072435206;4387.3857659927635;4391.253486907233;3831.214601006787;3849.40334248192;3866.284737323604;3879.4180872869297;3881.592432794516;4351.08627188294;4351.69538132334;4406.559344065632;4428.933565996513;4452.755098469615;5211;5215.556677682363;5215.921761173586;5256.868578285497;5266.868328291824;4411.713238639478;4422.137683151819;4425.137098265872;4426.22541055314;4462.129884672458;4532.161550113239;4543.977280113579;4582.633389328846;4685.718856868599;4732.739699316502;4777.235642297216;4785.49752276006;4792.137415265216;4815;4834.854954351375;5081.669691470042;5113.360829896263;5129.153689641167;5140.845774626767;5141.922871156861;4670.556297151901;4688.507706690884;4725.173094708343;4744.5492678195515;4830.8792280193275;4976;4979.377577802775;5002.199648056294;5025.748712564284;5053.949460505495;5002.424721157114;5039.622028347815;5043.420006699276;5047.470015648304;5055.9494405055475;4813.566778989908;4916.729579873141;5034.949650503448;5049.368828896388;5064.670796398223;5030;5061.924071139009;5062.417821950523;5067.467915868928;5116.769745361393;4751.9287210692555;4928.310036594951;4986.875328116681;4997.450280469073;5049.899002020013;5008.824691135793;5088.211327244283;5098.056859481007;5141.974290128525;5160.406553246425;4989.825356112518;4994.600431965507;4995.300657907968;4997.425296090996;5004.449510553909 15:36:24 INFO - PROCESS | 22676 | |1;ext.html;569.2713326941512;571.5199232644578;574.7528562718031;578.1010528627985;586.8415527807492;27184.86407567962;30111.698883011162;40370;41760.37359439611;41851.372229416585;41993.58006419935;42327.15345693084;42401.15197696052;42414.78792606033;42475;7773.922260777376;7920.80197995049;7925.722599709012;7928.960355198216;7936.880946785803;7560.735374261902;9926.702198934008;10216.846747298796;10224.84662730056;10542.789144217111;10322.48387580618;10401.167906567456;10412.843807342895;10421.947890260539;10426.530806113706;7595.658195381221;9980.151687106605;10232.02795734404;10314;10332.276740628122;9824.312298139133;10159.89840101597;10167.237457190666;10168.94915525425;10171.643992460302;38698.41952370717;39157.02107447308;39168.21663566741;39221.607783922365;39259;2866.641669791276;3071.17078388836;3082.922926926822;3100.7364374028234;3180.443422401094;29526.70473295261;29585.55621665655;29860.850695746598;30184.8490757547;30645.846770766228;3576.8748093816594;3580.3018411409525;3607.9278414431565;3621.3300539400293;3678.2091850252323;287890;289310;290030;292071;293207;32746.67253327484;33923.15192120217;33968.66031339704;56954.430455694914;57638.7118064407;34946;34999.30001399958;35032.82483587566;35105.64894351024;35107.473387899205;13917.721645567235;14051;14066.788998165035;14070.577882663332;14073.577792666236;9800.215982721365;10779.730506737238;11171.16216283782;11425.485853136628;11597.13021523389;7982.760517184493;7988.840223195503;8031.277185053256;8084.878726819103;8104.784282357694;3091.5517249998625;3103.4258662147577;3125.6249250090136;3132.201288671374;3141.1361875484426;854.6752234150982;873.6330741088631;902.7878448564713;937.6905621145006;1004.2819384140229;27378.589321160198;28554.428911421655;28824.279393015342;28981.7101828979;29374.412511749644;944.8204841080172;1000.3041802342761;1015.4313584392763;1070.7556105001856;1104.2325583719398;14703.411863525553;15403.306851191723;15623.921880390526;15813.525594232191;15880.682386352208;401815;455841;464611;465131;697914;51342.22986655203;51564.48435515597;51894;51909.22136167961;52189.47810521922;964.0841200859072;1160.2052593973156;1184.8933595976403;1190.3334132885611;1195.4441184849086;25873.482530349665;29564.826155423147;29655;29810.701892980793;30116.09651710452;42487.57512424836;43352;43397.34903976506;43404.34893476663;43422;315650;324161;324229;324987;325210;22737;24879.129230476847;25124.497510050063;25431.87284063568;25566.48867022612;42432.78783606062;42656.57343426526;42695;43443.56556434522;43590.56409435865;36140;36422.8178859104;37006;37127.25745485129;37561.624383755814;36365;36780.632193677724;37029.62970370369;37194.62805371912;37254.627453725116;36007.81996090108;36014;36352.27295454129;36367.818160909024;37584.624153758115;32193.678063219068;33960.66039339575;34206.65793342035;34222;34624;25585.616215756407;25803.354916127246;26208.475830483665;26412.603810942466;26741.331466713487 15:36:24 INFO - PROCESS | 22676 | |2;jquery.html;372.76702061211745;375.981200939953;376.60644626365445;385.9652631263186;389.6746216908881;16645.667086658275;16815.663686726275;17562.736558951627;17575.91212043938;17576.47270581883;16158.192090395492;16503.752443713354;16939.91530042348;16996.915015424936;17792.377266795665;4623.838165664202;4802.183628783097;4840.9515904840855;4843.4914333994775;4844.37023186987;3507.421275489541;3699.2416554606243;4753.144434001878;5418.864528386802;5740.311162660487;5778.855528611797;5790.305163380401;5800.593958422912;5801.216835727186;5811.767529298823;5506.284183056197;5515.834524964257;5517.282753242073;5519.6964166971;5522.944770552283;3413.6245013048547;5233.398159211702;5359.598030147716;5538.556915446755;5553.7778488860395;11357.829632555437;11376.829347559793;11398;11447.484863181176;19760.40718778439;1864.6363959027994;1903.9428817135508;2026.574419371919;2075.3462659262286;2222.7221597300336;9437.905620943771;9642.228621710317;9783.36408133469;9836.31145819793;10978.011978921855;1605.8474444927713;1681.630041390892;1716.7939847218288;1750.956226094345;1779.1015537153655;69199.65400172966;71048.28951710468;73019;73316;76189;10073.899261007296;10166.796664066676;10373.792524149474;10566;10567.63013294531;14343.282835858374;14424.927875360556;14429.711405771826;14434.278286085864;14453.205073721047;6683.097781799492;6845.965770171118;6890.345417185359;6898.206706228748;6984.952257161365;6306.527010474233;6314.810555683245;6315.084312774714;6399.424051835264;6423.164988551552;4458.464984201916;4487.663425243055;4514.525974772667;4518.051209246029;4600.42494688164;1663.6773764856725;1678.3370568625326;1750.859931205526;1826.8264514871066;1832.7196107955556;819.3117781063935;837.5770236030766;844.4595458906305;896.4442045931564;920.2132176988712;16574.917125414537;16866.241019154168;17430.91284543569;18183.636327273645;18245;847.4872702015192;873.7116751471999;894.9194572488507;906.909309069101;927.3415874729018;9760.316777825485;10210.234232432596;10217.284790064772;10273.760691437856;10365.481725913674;383575;390177;400084;400202;400892;78370;79598;80704;81899;82962;904.9909500904906;917.3991035871514;931.1433481197315;938.0525669074284;945.9479728614855;15771.526854194393;15807.604809879846;15824.60438489001;16059.518214453585;16442.34230630809;38393.4240986391;38494.42258366071;38702.2259554813;39034.80482597569;39142.80428597839;51289.743551282;53417.46582534125;53527.46472535225;53540.73229633827;53695.731521342146;6478.28738838718;6802.931970680428;7074.327938845801;7189.784306470814;7238.443734596876;8630.39587228888;11032;11368.374739389414;11469;11747.236429632047;9828.31201815866;9957.651482198089;10111.646092387025;10309.432981186106;10449.477526123816;9757.707268781947;9782.75543111428;10113.19094472455;10115.190784737351;10123.544440500047;9996;10032.297739158188;10078.395296282246;10449.477526123512;10615.734606634587;6847.726090956306;7048.436125110082;7062.964685176541;7129.643517823984;7153.964230179024;7817.843643126992;10186.592536298762;10226.386416815016;10293.43386113771;10362.896371036193 15:36:24 INFO - PROCESS | 22676 | |3;mootools.html;1356.9796453053202;1400.04796738218;1488.2838819155038;1670.4237038221825;1691.6024734187463;7084.929150708492;7492.063492063492;7539.246075392465;7539.547627142369;7546.207648196929;6379.744810207586;7017.122859642544;7190.53261538001;7242.891356629655;7386.889196662054;2196.3410976707046;2217.933461996143;2218.0794970087395;2221.8111460525874;2222.2666520048424;1845.7415961765364;2189.3431970408815;2203.636399993999;2216.711827462436;2218.545198234358;2104.1478201328487;2184.8252139828774;2186.387811412799;2198.5932602468515;2202.856814307073;2178.270279456384;2186.7375914890235;2196.2642514757576;2206.8455208135438;2225.4658881868395;2177.4882902517897;2183.257692384597;2191.298784388996;2197.846150769447;2203.2068455356057;7531.510451820669;7537.660805263777;7595.126560445564;7596.658150383246;7610.8477830443635;4311.935320970188;4457.576530229623;4458.108378324316;4482.39487669167;4500.617447516965;2742.2184677367054;2838.8580570971367;2885.0767754318626;2886.6536015678253;2900.4344152890194;868.4441957147368;874.8119154381775;885.5970533407284;885.8006948436615;893.0578239956843;9919.652812151613;9970.202383809277;9970.20238380935;9976.152027077707;9982.051705088077;3946.2699400610977;4055;4146.087860670647;4153.335466325373;4193.391958166048;8489.957550212333;8541.103184165573;8557.229849313591;8571.100034496412;8573.228409443056;4583.19794036049;4583.518730533313;4614.2617181250835;4615.353850460938;4615.976920115378;4161.542230354658;4174.895627609334;4191.7275377100395;4220.240356735817;4262.041040765865;3503.8248087595516;3531.7174626029855;3532.6997205237844;3576.8390422431053;3601.729870259741;1008.4453550547316;1095.8191898336845;1121.2039451989017;1126.5242532282737;1168.6727716239466;727.1238158019573;730.0399974034128;733.1239169192763;734.6693987705554;759.9164091949881;4842.539958703917;4963.081829861487;5023.6985780853265;5035.571976381975;5077.796888124508;861.2550144125287;862.6161358195668;865.4634126841398;866.0646501778065;881.299367003234;3343.398188326076;3344.3311337732553;3344.5317655528247;3362.176266814646;3471.1842716961496;8898.4660920345;9124.680636177834;9248.213901818286;9254.0283270257;9263.351565390358;7691.46159768822;9131.315151363675;9182.265418766483;9206.723798286062;9215.769605759775;6653.567518111307;6917.481188910851;6991.965040174767;7089.929100708927;7111.608861512666;5157.123289040797;5157.303763991813;5158.226266060121;5348.705821179872;5384.569234461155;8312.335013198808;8578.399512034348;8599.312055035458;8678.78303042429;8680.869786953326;8558.657653693783;8574.82850343002;8589.65641374363;8659.653613855628;8668.176523230282;6445.903311450238;6454.515911306765;6455.3221911700475;6487.351264873475;6495.577787443801;7110.4667149965;7131.144262688511;7156.427485801019;7183.20984691673;7244.673989670372;6410.487161027013;6542.771003014872;6618.569792963442;6680.899786503303;6739.292374300628;6511.674416279262;6518.380753828379;6541.182352205974;6555.049517819794;6605.372489613479;5932.228810254639;5959.731812068554;5965.164876917322;5992.550558708202;6006.549508786946;4300.763458009715;4469.664775141812;4492.989077457546;4522.706024108423;4557.339185817971;4451.643868490448;4487.282034874404;4543.522930092293;4568.81724731007;4627.167109920247 15:36:24 INFO - PROCESS | 22676 | |4;prototype.html;287.5793579716202;293.43074435594946;296.1840130440636;303.3447752853835;305.30238405243995;4886.731229782361;4913.631477639174;4928.408590969079;4938.901221975559;4955.876103097425;4885.975570122145;4909.607231421487;4912.656114072016;4913.434954980178;4916.532929371713;3663.999999999993;3783.186614877801;3823.560290566593;3885.048163200006;3915.7846318452475;3851.788151651658;3872.244912242114;3893.922121557567;3899.6295351941662;3901.824417901187;3681.4661874028384;3834.7153703509357;3859.421086836983;3861.1119442528234;3872.9031774205587;3769.6230376962358;3845.807709614536;3848.5381754189398;3852.9807350963347;3876.108495046127;3432.982835085809;3436.810975396377;3443.9311213775586;3450.292689998544;3467.167879708877;4791.257355109963;4806.158922188638;4813.085513752376;4822.517748225185;4833.275008748681;4142.378643203513;4348.7390756554705;4354.869353919387;4400.911981760347;4499.820007199708;5133.871653208662;5134.255532947702;5159.329287192679;5161.793528258865;5200.3759548854005;3531.7351198659944;3669.348793043141;3675.8788569486296;3695.981520092409;3713.1274150574595;5344.305240318734;5364.651297665679;5376.758045887945;5380.623356365056;5387.299651045339;3695.6858667013603;3697.8890633281044;3700.5374328208973;3701.9074523137137;3719.497867787868;3765.5669597996034;3781.7352785305316;3789.412641040643;3807.276617442677;3808.27642747877;4145.590499230276;4287.807048682755;4292.055747735493;4294.677899157576;4358.193734159191;5127.179651255781;5134.127198376285;5152.845414637567;5160.200168973815;5170.276161337416;1301.1542497376781;1321.6827961289414;1324.2253281830044;1325.6685828542863;1326.085001349066;842.9336888835628;844.2950136635977;862.2627653356416;864.7535452396037;871.5990644303564;4401;4511.751853648081;4519.689290105833;4526.932096018562;4527.4340707411575;5316.813911513156;5328.014317351302;5332.946670533245;5341.839744807662;5345.545628621533;4354.629856462237;4506.954930450719;4515.683902126885;4517.141743068806;4561.384213131185;2803.9859800700865;2819.7039310872474;2828.533292006837;2832.589274555178;2853.372258103214;4965.361430727851;4974.5771609413605;4975.527324904201;4996.575291100225;4997.40083173381;5213.921791173136;5467.234587157801;5478.205660179252;5507.146392309199;5516.531094856902;5004.824831130893;5016.799328026838;5030.496950305013;5042.899142017212;5070.163423035232;5374.919376209438;5387.245785590099;5414.891702165856;5439.619226654097;5451.672899626035;5188.429272779916;5259.47405259471;5304.893902122013;5305.257263983122;5318.228856815817;5016.54520189141;5031.421386540448;5044.192929131322;5057.898842023065;5121.948780512147;3864.4976153099915;3867.974986628602;3875.2830726315724;3882;3886.84452621892;5123.948760512347;5358.356997160366;5381.381141168816;5443.210734443563;5456.61803673739;5172.948270517397;5181.430042695376;5202.479752024768;5242.764075616683;5269.657472264291;5141.323953373356;5231.372235331784;5253.474652534869;5258.342707161605;5281.445448228034;4904.485029071896;5162.277281180563;5170.612204084633;5171.974140129275;5175.896482070262;5044.54599086084;5222.67590922036;5241.921371179359;5244.9737751310995;5318.361796584435;5164.302819119371;5174.637775355689;5178;5179.352580927384;5185.870353241048 15:36:24 INFO - PROCESS | 22676 | |5;yui.html;659.2155335151169;660.7423104989053;702.9472789540782;723.424877222608;741.7250064454713;12109.333986630743;12951.870481295184;12976.480940762358;12980.480780768758;12986.610401687953;12566.24602523847;12619.43212555435;12635.747285054293;12642.683932901682;12651.62045138645;625.5933643131978;765.9157492675802;769.6113462701356;787.6494959742917;795.4630624328584;774.2915232562227;775.8099265679916;777.6189667063155;778.8792737125755;781.5896654256504;837.5100566168799;840.3025488844245;841.1168273313027;841.221869770463;841.734853521142;660.6762686283711;679.5684740189965;682.1643486728767;682.6552590941543;835.340081335744;596.9701514924236;602.9005214139705;608.5010291560922;609.6250805754473;609.6524980760968;12652.936735316356;12728.872711272954;12752.362381880961;12766.872331276661;12774.850263476239;12542.937285313606;12698.365081745877;12834.229946203164;12981.415836287295;13039.152455090483;11679.532818687243;11725.472353744102;11738.823917641243;11944.402779861059;12211;4204.936925946114;4217.641500472463;4285.400043993844;4306;4418.3372494125815;58900.41099588992;59509.40490595082;59522;59808.70095649538;59816.40183598195;10545.367277963192;10661.626843060456;10691.411972341595;10799.136069114453;10858.619948301772;19280.32518861833;19290.131944062552;19294.22823087089;19317.61364772697;19328.903355483413;6420.678966051679;6442.387973142544;6504.089427480157;6510.21877374718;6694.665266736741;3784.6404591563755;3810.371288737327;3825.6556909878245;3846.538415390171;3862.7682339059747;2690.0450340129364;2782.7912906531687;2783.819051761629;2786.247713117447;2787.6933537311284;462.8727100047514;466.7059752355994;470.4025887123403;472.1713392995271;478.930555069513;524.7481209019692;542.734060310445;578.6730497269114;582.973766180523;599.6551982610017;7718.379677786973;8040;8159.632816523271;8189.426740128253;8448.66205351779;538.9703566303891;551.4402881075712;570.6861226325587;588.0561698473973;611.788932818177;3832.9616703832603;3871.806409679561;3950.6839452843706;3986.342253528135;4124.52567954689;82412.58793705993;84875;86118;87738.56130719306;93963.53018235002;75115.62442187863;77669.61165194138;81123.59438202772;81303.18696813074;82309;43134.78432607817;43993;68298.65850670714;70337.64831175914;72795;9201.263898887939;9240.168384845396;9480.431174129299;9510.809783804423;9570.712878613653;38496.2300753989;42308.57691423046;43506.56493435025;45131.7743411294;45166;58940;59122;60591.69704151451;61577.69211153915;61589.384106160156;11527.423628818358;11936.462859171184;12072;12193.634190974284;12557.309347985949;12696.619101426972;12803;12831.679208019874;13483.528076517667;13625.386857591631;12283.754324913272;12371;12472;13043.347832608142;13049.869501304865;12558.246505209716;12572.622821315375;12620.55828046014;12831.10182287231;13407.396667150197;10820.458977051274;11823.290602563873;11855.644330670093;11905.642830715093;11944.283342999448;9874.901250987397;9890.159336456481;10261.384316941007;10317.9484102579;10458.267921245744;9287.582058807235;9297.495906386059;9543.284253680862;10134.586754432723;10217.182625389802 15:36:24 INFO - PROCESS | 22676 | __end_tp_report 15:36:24 INFO - PROCESS | 22676 | __start_cc_report 15:36:24 INFO - PROCESS | 22676 | _x_x_mozilla_cycle_collect,1113 15:36:24 INFO - PROCESS | 22676 | __end_cc_report 15:36:24 INFO - PROCESS | 22676 | __startTimestamp1468276584237__endTimestamp 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | ------- Summary: start ------- 15:36:24 INFO - PROCESS | 22676 | Number of tests: 6 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | [#0] dojo.html Cycles:165 Average:4761.75 Median:4850.81 stddev:512.74 (10.6%) stddev-sans-first:486.46 15:36:24 INFO - PROCESS | 22676 | Values: 2636.6 2641.9 2675.1 2688.5 2691.5 4675.1 4727.9 4752.9 4799.9 4850.8 4668.2 4729.4 4736.9 4782.8 4785.9 4110.4 4353.3 4384.9 4432.6 4521.1 4447.2 4489.2 4503.0 4508.2 4527.3 4306.4 4355.8 4366.5 4414.2 4434.0 4399.8 4409.7 4436.0 4486.9 4500.7 4449.5 4455.2 4469.7 4490.5 4495.8 4690.4 4731.8 4818.2 4926.6 4927.4 5193.6 5243.5 5292.4 5295.4 5296.5 5287.5 5299.0 5305.9 5320.6 5325.3 4665.8 4732.6 4807.0 4911.6 4960.1 5252.2 5271.7 5275.6 5320.3 5335.7 5055.0 5086.5 5097.5 5100.9 5109.1 4990.5 5125.1 5137.7 5226.8 5240.8 5192.6 5196.9 5219.3 5221.7 5225.3 5186.1 5202.3 5202.8 5204.5 5239.7 4293.9 4336.7 4341.1 4387.4 4391.3 3831.2 3849.4 3866.3 3879.4 3881.6 4351.1 4351.7 4406.6 4428.9 4452.8 5211.0 5215.6 5215.9 5256.9 5266.9 4411.7 4422.1 4425.1 4426.2 4462.1 4532.2 4544.0 4582.6 4685.7 4732.7 4777.2 4785.5 4792.1 4815.0 4834.9 5081.7 5113.4 5129.2 5140.8 5141.9 4670.6 4688.5 4725.2 4744.5 4830.9 4976.0 4979.4 5002.2 5025.7 5053.9 5002.4 5039.6 5043.4 5047.5 5055.9 4813.6 4916.7 5034.9 5049.4 5064.7 5030.0 5061.9 5062.4 5067.5 5116.8 4751.9 4928.3 4986.9 4997.5 5049.9 5008.8 5088.2 5098.1 5142.0 5160.4 4989.8 4994.6 4995.3 4997.4 5004.4 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | [#1] ext.html Cycles:180 Average:51012.24 Median:34214.33 stddev:102636.74 (300.0%) stddev-sans-first:102854.79 15:36:24 INFO - PROCESS | 22676 | Values: 569.3 571.5 574.8 578.1 586.8 27184.9 30111.7 40370.0 41760.4 41851.4 41993.6 42327.2 42401.2 42414.8 42475.0 7773.9 7920.8 7925.7 7929.0 7936.9 7560.7 9926.7 10216.8 10224.8 10542.8 10322.5 10401.2 10412.8 10421.9 10426.5 7595.7 9980.2 10232.0 10314.0 10332.3 9824.3 10159.9 10167.2 10168.9 10171.6 38698.4 39157.0 39168.2 39221.6 39259.0 2866.6 3071.2 3082.9 3100.7 3180.4 29526.7 29585.6 29860.9 30184.8 30645.8 3576.9 3580.3 3607.9 3621.3 3678.2 287890.0 289310.0 290030.0 292071.0 293207.0 32746.7 33923.2 33968.7 56954.4 57638.7 34946.0 34999.3 35032.8 35105.6 35107.5 13917.7 14051.0 14066.8 14070.6 14073.6 9800.2 10779.7 11171.2 11425.5 11597.1 7982.8 7988.8 8031.3 8084.9 8104.8 3091.6 3103.4 3125.6 3132.2 3141.1 854.7 873.6 902.8 937.7 1004.3 27378.6 28554.4 28824.3 28981.7 29374.4 944.8 1000.3 1015.4 1070.8 1104.2 14703.4 15403.3 15623.9 15813.5 15880.7 401815.0 455841.0 464611.0 465131.0 697914.0 51342.2 51564.5 51894.0 51909.2 52189.5 964.1 1160.2 1184.9 1190.3 1195.4 25873.5 29564.8 29655.0 29810.7 30116.1 42487.6 43352.0 43397.3 43404.3 43422.0 315650.0 324161.0 324229.0 324987.0 325210.0 22737.0 24879.1 25124.5 25431.9 25566.5 42432.8 42656.6 42695.0 43443.6 43590.6 36140.0 36422.8 37006.0 37127.3 37561.6 36365.0 36780.6 37029.6 37194.6 37254.6 36007.8 36014.0 36352.3 36367.8 37584.6 32193.7 33960.7 34206.7 34222.0 34624.0 25585.6 25803.4 26208.5 26412.6 26741.3 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | [#2] jquery.html Cycles:180 Average:24515.96 Median:202675.23 stddev:65427.57 (32.3%) stddev-sans-first:65586.00 15:36:24 INFO - PROCESS | 22676 | Values: 372.8 376.0 376.6 386.0 389.7 16645.7 16815.7 17562.7 17575.9 17576.5 16158.2 16503.8 16939.9 16996.9 17792.4 4623.8 4802.2 4841.0 4843.5 4844.4 3507.4 3699.2 4753.1 5418.9 5740.3 5778.9 5790.3 5800.6 5801.2 5811.8 5506.3 5515.8 5517.3 5519.7 5522.9 3413.6 5233.4 5359.6 5538.6 5553.8 11357.8 11376.8 11398.0 11447.5 19760.4 1864.6 1903.9 2026.6 2075.3 2222.7 9437.9 9642.2 9783.4 9836.3 10978.0 1605.8 1681.6 1716.8 1751.0 1779.1 69199.7 71048.3 73019.0 73316.0 76189.0 10073.9 10166.8 10373.8 10566.0 10567.6 14343.3 14424.9 14429.7 14434.3 14453.2 6683.1 6846.0 6890.3 6898.2 6985.0 6306.5 6314.8 6315.1 6399.4 6423.2 4458.5 4487.7 4514.5 4518.1 4600.4 1663.7 1678.3 1750.9 1826.8 1832.7 819.3 837.6 844.5 896.4 920.2 16574.9 16866.2 17430.9 18183.6 18245.0 847.5 873.7 894.9 906.9 927.3 9760.3 10210.2 10217.3 10273.8 10365.5 383575.0 390177.0 400084.0 400202.0 400892.0 78370.0 79598.0 80704.0 81899.0 82962.0 905.0 917.4 931.1 938.1 945.9 15771.5 15807.6 15824.6 16059.5 16442.3 38393.4 38494.4 38702.2 39034.8 39142.8 51289.7 53417.5 53527.5 53540.7 53695.7 6478.3 6802.9 7074.3 7189.8 7238.4 8630.4 11032.0 11368.4 11469.0 11747.2 9828.3 9957.7 10111.6 10309.4 10449.5 9757.7 9782.8 10113.2 10115.2 10123.5 9996.0 10032.3 10078.4 10449.5 10615.7 6847.7 7048.4 7063.0 7129.6 7154.0 7817.8 10186.6 10226.4 10293.4 10362.9 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | [#3] mootools.html Cycles:180 Average:4940.90 Median:5945.98 stddev:2696.32 (45.3%) stddev-sans-first:2690.43 15:36:24 INFO - PROCESS | 22676 | Values: 1357.0 1400.0 1488.3 1670.4 1691.6 7084.9 7492.1 7539.2 7539.5 7546.2 6379.7 7017.1 7190.5 7242.9 7386.9 2196.3 2217.9 2218.1 2221.8 2222.3 1845.7 2189.3 2203.6 2216.7 2218.5 2104.1 2184.8 2186.4 2198.6 2202.9 2178.3 2186.7 2196.3 2206.8 2225.5 2177.5 2183.3 2191.3 2197.8 2203.2 7531.5 7537.7 7595.1 7596.7 7610.8 4311.9 4457.6 4458.1 4482.4 4500.6 2742.2 2838.9 2885.1 2886.7 2900.4 868.4 874.8 885.6 885.8 893.1 9919.7 9970.2 9970.2 9976.2 9982.1 3946.3 4055.0 4146.1 4153.3 4193.4 8490.0 8541.1 8557.2 8571.1 8573.2 4583.2 4583.5 4614.3 4615.4 4616.0 4161.5 4174.9 4191.7 4220.2 4262.0 3503.8 3531.7 3532.7 3576.8 3601.7 1008.4 1095.8 1121.2 1126.5 1168.7 727.1 730.0 733.1 734.7 759.9 4842.5 4963.1 5023.7 5035.6 5077.8 861.3 862.6 865.5 866.1 881.3 3343.4 3344.3 3344.5 3362.2 3471.2 8898.5 9124.7 9248.2 9254.0 9263.4 7691.5 9131.3 9182.3 9206.7 9215.8 6653.6 6917.5 6992.0 7089.9 7111.6 5157.1 5157.3 5158.2 5348.7 5384.6 8312.3 8578.4 8599.3 8678.8 8680.9 8558.7 8574.8 8589.7 8659.7 8668.2 6445.9 6454.5 6455.3 6487.4 6495.6 7110.5 7131.1 7156.4 7183.2 7244.7 6410.5 6542.8 6618.6 6680.9 6739.3 6511.7 6518.4 6541.2 6555.0 6605.4 5932.2 5959.7 5965.2 5992.6 6006.5 4300.8 4469.7 4493.0 4522.7 4557.3 4451.6 4487.3 4543.5 4568.8 4627.2 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | [#4] prototype.html Cycles:180 Average:4302.92 Median:4913.53 stddev:1258.82 (25.6%) stddev-sans-first:1225.75 15:36:24 INFO - PROCESS | 22676 | Values: 287.6 293.4 296.2 303.3 305.3 4886.7 4913.6 4928.4 4938.9 4955.9 4886.0 4909.6 4912.7 4913.4 4916.5 3664.0 3783.2 3823.6 3885.0 3915.8 3851.8 3872.2 3893.9 3899.6 3901.8 3681.5 3834.7 3859.4 3861.1 3872.9 3769.6 3845.8 3848.5 3853.0 3876.1 3433.0 3436.8 3443.9 3450.3 3467.2 4791.3 4806.2 4813.1 4822.5 4833.3 4142.4 4348.7 4354.9 4400.9 4499.8 5133.9 5134.3 5159.3 5161.8 5200.4 3531.7 3669.3 3675.9 3696.0 3713.1 5344.3 5364.7 5376.8 5380.6 5387.3 3695.7 3697.9 3700.5 3701.9 3719.5 3765.6 3781.7 3789.4 3807.3 3808.3 4145.6 4287.8 4292.1 4294.7 4358.2 5127.2 5134.1 5152.8 5160.2 5170.3 1301.2 1321.7 1324.2 1325.7 1326.1 842.9 844.3 862.3 864.8 871.6 4401.0 4511.8 4519.7 4526.9 4527.4 5316.8 5328.0 5332.9 5341.8 5345.5 4354.6 4507.0 4515.7 4517.1 4561.4 2804.0 2819.7 2828.5 2832.6 2853.4 4965.4 4974.6 4975.5 4996.6 4997.4 5213.9 5467.2 5478.2 5507.1 5516.5 5004.8 5016.8 5030.5 5042.9 5070.2 5374.9 5387.2 5414.9 5439.6 5451.7 5188.4 5259.5 5304.9 5305.3 5318.2 5016.5 5031.4 5044.2 5057.9 5121.9 3864.5 3868.0 3875.3 3882.0 3886.8 5123.9 5358.4 5381.4 5443.2 5456.6 5172.9 5181.4 5202.5 5242.8 5269.7 5141.3 5231.4 5253.5 5258.3 5281.4 4904.5 5162.3 5170.6 5172.0 5175.9 5044.5 5222.7 5241.9 5245.0 5318.4 5164.3 5174.6 5178.0 5179.4 5185.9 15:36:24 INFO - PROCESS | 22676 | 15:36:24 INFO - PROCESS | 22676 | [#5] yui.html Cycles:180 Average:16912.36 Median:45148.89 stddev:22980.01 (50.9%) stddev-sans-first:23012.06 15:36:24 INFO - PROCESS | 22676 | Values: 659.2 660.7 702.9 723.4 741.7 12109.3 12951.9 12976.5 12980.5 12986.6 12566.2 12619.4 12635.7 12642.7 12651.6 625.6 765.9 769.6 787.6 795.5 774.3 775.8 777.6 778.9 781.6 837.5 840.3 841.1 841.2 841.7 660.7 679.6 682.2 682.7 835.3 597.0 602.9 608.5 609.6 609.7 12652.9 12728.9 12752.4 12766.9 12774.9 12542.9 12698.4 12834.2 12981.4 13039.2 11679.5 11725.5 11738.8 11944.4 12211.0 4204.9 4217.6 4285.4 4306.0 4418.3 58900.4 59509.4 59522.0 59808.7 59816.4 10545.4 10661.6 10691.4 10799.1 10858.6 19280.3 19290.1 19294.2 19317.6 19328.9 6420.7 6442.4 6504.1 6510.2 6694.7 3784.6 3810.4 3825.7 3846.5 3862.8 2690.0 2782.8 2783.8 2786.2 2787.7 462.9 466.7 470.4 472.2 478.9 524.7 542.7 578.7 583.0 599.7 7718.4 8040.0 8159.6 8189.4 8448.7 539.0 551.4 570.7 588.1 611.8 3833.0 3871.8 3950.7 3986.3 4124.5 82412.6 84875.0 86118.0 87738.6 93963.5 75115.6 77669.6 81123.6 81303.2 82309.0 43134.8 43993.0 68298.7 70337.6 72795.0 9201.3 9240.2 9480.4 9510.8 9570.7 38496.2 42308.6 43506.6 45131.8 45166.0 58940.0 59122.0 60591.7 61577.7 61589.4 11527.4 11936.5 12072.0 12193.6 12557.3 12696.6 12803.0 12831.7 13483.5 13625.4 12283.8 12371.0 12472.0 13043.3 13049.9 12558.2 12572.6 12620.6 12831.1 13407.4 10820.5 11823.3 11855.6 11905.6 11944.3 9874.9 9890.2 10261.4 10317.9 10458.3 9287.6 9297.5 9543.3 10134.6 10217.2 15:36:24 INFO - PROCESS | 22676 | -------- Summary: end -------- 15:36:24 INFO - PROCESS | 22676 | 15:36:25 INFO - TEST-INFO | 22676: exit 0 15:36:25 INFO - TEST-OK | dromaeo_css | took 1119467ms 15:36:25 INFO - TEST-START | kraken 15:36:25 INFO - operating with platform_type : linux_ 15:36:25 INFO - Initialising browser for kraken test... 15:36:25 INFO - TEST-INFO | started process 22860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2tMpq5/profile http://localhost:32945/getInfo.html) 15:36:28 INFO - PROCESS | 22860 | __metrics Screen width/height:1600/1200 15:36:28 INFO - PROCESS | 22860 | colorDepth:24 15:36:28 INFO - PROCESS | 22860 | Browser inner width/height: 1024/697 15:36:28 INFO - PROCESS | 22860 | __metrics 15:36:29 INFO - PROCESS | 22860 | 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" 15:36:29 INFO - PROCESS | 22860 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:36:30 INFO - TEST-INFO | 22860: exit 0 15:36:30 INFO - Browser initialized. 15:36:30 INFO - Running cycle 1/1 for kraken test... 15:36:30 INFO - TEST-INFO | started process 22940 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2tMpq5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:42 INFO - PROCESS | 22940 | RSS: Main: 158691328 15:36:42 INFO - PROCESS | 22940 | 15:36:45 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32945/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:46 INFO - PROCESS | 22940 | RSS: Main: 216416256 15:36:46 INFO - PROCESS | 22940 | 15:36:48 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32945/tests/kraken/driver.html?testName=audio-dft) 15:36:49 INFO - PROCESS | 22940 | RSS: Main: 202670080 15:36:49 INFO - PROCESS | 22940 | 15:36:52 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32945/tests/kraken/driver.html?testName=audio-fft) 15:36:53 INFO - PROCESS | 22940 | RSS: Main: 270970880 15:36:53 INFO - PROCESS | 22940 | 15:36:55 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32945/tests/kraken/driver.html?testName=audio-oscillator) 15:36:56 INFO - PROCESS | 22940 | RSS: Main: 181510144 15:36:56 INFO - PROCESS | 22940 | 15:36:58 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:58 INFO - PROCESS | 22940 | RSS: Main: 185503744 15:36:58 INFO - PROCESS | 22940 | 15:37:02 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32945/tests/kraken/driver.html?testName=imaging-darkroom) 15:37:02 INFO - PROCESS | 22940 | RSS: Main: 221270016 15:37:02 INFO - PROCESS | 22940 | 15:37:06 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32945/tests/kraken/driver.html?testName=imaging-desaturate) 15:37:06 INFO - PROCESS | 22940 | RSS: Main: 217739264 15:37:06 INFO - PROCESS | 22940 | 15:37:09 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32945/tests/kraken/driver.html?testName=json-parse-financial) 15:37:10 INFO - PROCESS | 22940 | RSS: Main: 220340224 15:37:10 INFO - PROCESS | 22940 | 15:37:12 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:37:12 INFO - PROCESS | 22940 | RSS: Main: 207446016 15:37:12 INFO - PROCESS | 22940 | 15:37:14 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:37:15 INFO - PROCESS | 22940 | RSS: Main: 210935808 15:37:15 INFO - PROCESS | 22940 | 15:37:17 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:18 INFO - PROCESS | 22940 | RSS: Main: 207384576 15:37:18 INFO - PROCESS | 22940 | 15:37:20 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:21 INFO - PROCESS | 22940 | RSS: Main: 220798976 15:37:21 INFO - PROCESS | 22940 | 15:37:24 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:24 INFO - PROCESS | 22940 | RSS: Main: 222564352 15:37:24 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | Cycle 1(1): loaded http://localhost:32945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32945/tests/kraken/driver.html?testName=ai-astar) 15:37:26 INFO - PROCESS | 22940 | RSS: Main: 264744960 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | __start_tp_report 15:37:26 INFO - PROCESS | 22940 | _x_x_mozilla_page_load 15:37:26 INFO - PROCESS | 22940 | _x_x_mozilla_page_load_details 15:37:26 INFO - PROCESS | 22940 | |i|pagename|runs| 15:37:26 INFO - PROCESS | 22940 | |0;ai-astar;108;115;114;115;117;114;114;114;114;114 15:37:26 INFO - PROCESS | 22940 | |1;audio-beat-detection;149;121;124;170;122;124;147;121;168;122 15:37:26 INFO - PROCESS | 22940 | |2;audio-dft;161;160;161;162;161;162;169;160;161;163 15:37:26 INFO - PROCESS | 22940 | |3;audio-fft;76;76;76;119;76;77;75;75;110;76 15:37:26 INFO - PROCESS | 22940 | |4;audio-oscillator;85;87;126;85;85;84;118;84;85;130 15:37:26 INFO - PROCESS | 22940 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;117;117;118 15:37:26 INFO - PROCESS | 22940 | |6;imaging-darkroom;146;146;146;147;147;146;147;148;146;146 15:37:26 INFO - PROCESS | 22940 | |7;imaging-desaturate;95;95;94;95;94;95;96;95;95;95 15:37:26 INFO - PROCESS | 22940 | |8;json-parse-financial;63;64;71;64;63;67;63;63;62;63 15:37:26 INFO - PROCESS | 22940 | |9;json-stringify-tinderbox;65;65;65;65;66;65;66;69;65;65 15:37:26 INFO - PROCESS | 22940 | |10;stanford-crypto-aes;84;80;79;78;85;78;79;79;79;80 15:37:26 INFO - PROCESS | 22940 | |11;stanford-crypto-ccm;130;134;132;140;133;129;129;128;170;148 15:37:26 INFO - PROCESS | 22940 | |12;stanford-crypto-pbkdf2;172;169;169;173;174;172;170;165;167;168 15:37:26 INFO - PROCESS | 22940 | |13;stanford-crypto-sha256-iterative;69;67;69;68;66;67;69;66;66;65 15:37:26 INFO - PROCESS | 22940 | __end_tp_report 15:37:26 INFO - PROCESS | 22940 | __start_cc_report 15:37:26 INFO - PROCESS | 22940 | _x_x_mozilla_cycle_collect,1908 15:37:26 INFO - PROCESS | 22940 | __end_cc_report 15:37:26 INFO - PROCESS | 22940 | __startTimestamp1468276646924__endTimestamp 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | ------- Summary: start ------- 15:37:26 INFO - PROCESS | 22940 | Number of tests: 14 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:2.28 (2.0%) stddev-sans-first:1.01 15:37:26 INFO - PROCESS | 22940 | Values: 108.0 115.0 114.0 115.0 117.0 114.0 114.0 114.0 114.0 114.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:135.50 stddev:19.98 (14.7%) stddev-sans-first:20.70 15:37:26 INFO - PROCESS | 22940 | Values: 149.0 121.0 124.0 170.0 122.0 124.0 147.0 121.0 168.0 122.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#2] audio-dft Cycles:10 Average:162.00 Median:161.50 stddev:2.62 (1.6%) stddev-sans-first:2.76 15:37:26 INFO - PROCESS | 22940 | Values: 161.0 160.0 161.0 162.0 161.0 162.0 169.0 160.0 161.0 163.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:16.43 (21.6%) stddev-sans-first:17.20 15:37:26 INFO - PROCESS | 22940 | Values: 76.0 76.0 76.0 119.0 76.0 77.0 75.0 75.0 110.0 76.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:85.00 stddev:19.39 (22.8%) stddev-sans-first:20.09 15:37:26 INFO - PROCESS | 22940 | Values: 85.0 87.0 126.0 85.0 85.0 84.0 118.0 84.0 85.0 130.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:37:26 INFO - PROCESS | 22940 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 118.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 15:37:26 INFO - PROCESS | 22940 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 147.0 148.0 146.0 146.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 15:37:26 INFO - PROCESS | 22940 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 96.0 95.0 95.0 95.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:63.50 stddev:2.71 (4.3%) stddev-sans-first:2.83 15:37:26 INFO - PROCESS | 22940 | Values: 63.0 64.0 71.0 64.0 63.0 67.0 63.0 63.0 62.0 63.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:65.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 15:37:26 INFO - PROCESS | 22940 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 66.0 69.0 65.0 65.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.50 stddev:2.42 (3.0%) stddev-sans-first:2.12 15:37:26 INFO - PROCESS | 22940 | Values: 84.0 80.0 79.0 78.0 85.0 78.0 79.0 79.0 79.0 80.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:133.50 stddev:13.02 (9.8%) stddev-sans-first:13.54 15:37:26 INFO - PROCESS | 22940 | Values: 130.0 134.0 132.0 140.0 133.0 129.0 129.0 128.0 170.0 148.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:171.00 stddev:2.85 (1.7%) stddev-sans-first:2.92 15:37:26 INFO - PROCESS | 22940 | Values: 172.0 169.0 169.0 173.0 174.0 172.0 170.0 165.0 167.0 168.0 15:37:26 INFO - PROCESS | 22940 | 15:37:26 INFO - PROCESS | 22940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.48 (2.2%) stddev-sans-first:1.41 15:37:26 INFO - PROCESS | 22940 | Values: 69.0 67.0 69.0 68.0 66.0 67.0 69.0 66.0 66.0 65.0 15:37:26 INFO - PROCESS | 22940 | -------- Summary: end -------- 15:37:26 INFO - PROCESS | 22940 | 15:37:27 INFO - TEST-INFO | 22940: exit 0 15:37:28 INFO - TEST-OK | kraken | took 62985ms 15:37:28 INFO - SUITE-END | took 1182s 15:37:28 INFO - Completed test suite (00:19:42) 15:37:28 INFO - javascript benchmark 15:37:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2636.617690434887, 2641.8546979916096, 2675.0770984010514, 2688.462307538493, 2691.5020721166575, 4675.088357770236, 4727.881802954924, 4752.857414277577, 4799.928001079987, 4850.80596776129, 4668.206404911165, 4729.361536192613, 4736.928946065812, 4782.808687652489, 4785.880352991177, 4110.403991421257, 4353.2599458092045, 4384.912301753963, 4432.645388368939, 4521.118381915528, 4447.221736196169, 4489.236829738952, 4502.977485112586, 4508.188526065307, 4527.275635898257, 4306.35404689296, 4355.760433176174, 4366.519682834885, 4414.183376075436, 4433.9556604433865, 4399.846005389796, 4409.691321607489, 4436.0, 4486.8878278042985, 4500.707454015478, 4449.488308844492, 4455.198064348416, 4469.7318160910445, 4490.528494508095, 4495.775211239425, 4690.413698287714, 4731.7634118293945, 4818.229083346648, 4926.5812405945535, 4927.43334516528, 5193.610479214036, 5243.501867322599, 5292.417834038252, 5295.443978382292, 5296.470352964711, 5287.497687719699, 5299.046171689094, 5305.867353316159, 5320.600954928357, 5325.307709997675, 4665.790039448233, 4732.621390288768, 4807.038592281558, 4911.631627627907, 4960.057589058068, 5252.2384254282915, 5271.710055946883, 5275.551578115903, 5320.308359913264, 5335.653182543122, 5055.0, 5086.465921078234, 5097.490250974873, 5100.948990510122, 5109.105906466428, 4990.525900039491, 5125.077486052546, 5137.743112844343, 5226.764795584208, 5240.816571419982, 5192.61055420845, 5196.8960620787375, 5219.321488206585, 5221.660592061504, 5225.346831646044, 5186.118359878829, 5202.3497062867145, 5202.81790137351, 5204.479552044765, 5239.659422137549, 4293.892652683708, 4336.696431249782, 4341.110072435206, 4387.3857659927635, 4391.253486907233, 3831.214601006787, 3849.40334248192, 3866.284737323604, 3879.4180872869297, 3881.592432794516, 4351.08627188294, 4351.69538132334, 4406.559344065632, 4428.933565996513, 4452.755098469615, 5211.0, 5215.556677682363, 5215.921761173586, 5256.868578285497, 5266.868328291824, 4411.713238639478, 4422.137683151819, 4425.137098265872, 4426.22541055314, 4462.129884672458, 4532.161550113239, 4543.977280113579, 4582.633389328846, 4685.718856868599, 4732.739699316502, 4777.235642297216, 4785.49752276006, 4792.137415265216, 4815.0, 4834.854954351375, 5081.669691470042, 5113.360829896263, 5129.153689641167, 5140.845774626767, 5141.922871156861, 4670.556297151901, 4688.507706690884, 4725.173094708343, 4744.5492678195515, 4830.8792280193275, 4976.0, 4979.377577802775, 5002.199648056294, 5025.748712564284, 5053.949460505495, 5002.424721157114, 5039.622028347815, 5043.420006699276, 5047.470015648304, 5055.9494405055475, 4813.566778989908, 4916.729579873141, 5034.949650503448, 5049.368828896388, 5064.670796398223, 5030.0, 5061.924071139009, 5062.417821950523, 5067.467915868928, 5116.769745361393, 4751.9287210692555, 4928.310036594951, 4986.875328116681, 4997.450280469073, 5049.899002020013, 5008.824691135793, 5088.211327244283, 5098.056859481007, 5141.974290128525, 5160.406553246425, 4989.825356112518, 4994.600431965507, 4995.300657907968, 4997.425296090996, 5004.449510553909], "value": 4728.418194794211, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.2713326941512, 571.5199232644578, 574.7528562718031, 578.1010528627985, 586.8415527807492, 27184.86407567962, 30111.698883011162, 40370.0, 41760.37359439611, 41851.372229416585, 41993.58006419935, 42327.15345693084, 42401.15197696052, 42414.78792606033, 42475.0, 7773.922260777376, 7920.80197995049, 7925.722599709012, 7928.960355198216, 7936.880946785803, 7560.735374261902, 9926.702198934008, 10216.846747298796, 10224.84662730056, 10542.789144217111, 10322.48387580618, 10401.167906567456, 10412.843807342895, 10421.947890260539, 10426.530806113706, 7595.658195381221, 9980.151687106605, 10232.02795734404, 10314.0, 10332.276740628122, 9824.312298139133, 10159.89840101597, 10167.237457190666, 10168.94915525425, 10171.643992460302, 38698.41952370717, 39157.02107447308, 39168.21663566741, 39221.607783922365, 39259.0, 2866.641669791276, 3071.17078388836, 3082.922926926822, 3100.7364374028234, 3180.443422401094, 29526.70473295261, 29585.55621665655, 29860.850695746598, 30184.8490757547, 30645.846770766228, 3576.8748093816594, 3580.3018411409525, 3607.9278414431565, 3621.3300539400293, 3678.2091850252323, 287890.0, 289310.0, 290030.0, 292071.0, 293207.0, 32746.67253327484, 33923.15192120217, 33968.66031339704, 56954.430455694914, 57638.7118064407, 34946.0, 34999.30001399958, 35032.82483587566, 35105.64894351024, 35107.473387899205, 13917.721645567235, 14051.0, 14066.788998165035, 14070.577882663332, 14073.577792666236, 9800.215982721365, 10779.730506737238, 11171.16216283782, 11425.485853136628, 11597.13021523389, 7982.760517184493, 7988.840223195503, 8031.277185053256, 8084.878726819103, 8104.784282357694, 3091.5517249998625, 3103.4258662147577, 3125.6249250090136, 3132.201288671374, 3141.1361875484426, 854.6752234150982, 873.6330741088631, 902.7878448564713, 937.6905621145006, 1004.2819384140229, 27378.589321160198, 28554.428911421655, 28824.279393015342, 28981.7101828979, 29374.412511749644, 944.8204841080172, 1000.3041802342761, 1015.4313584392763, 1070.7556105001856, 1104.2325583719398, 14703.411863525553, 15403.306851191723, 15623.921880390526, 15813.525594232191, 15880.682386352208, 401815.0, 455841.0, 464611.0, 465131.0, 697914.0, 51342.22986655203, 51564.48435515597, 51894.0, 51909.22136167961, 52189.47810521922, 964.0841200859072, 1160.2052593973156, 1184.8933595976403, 1190.3334132885611, 1195.4441184849086, 25873.482530349665, 29564.826155423147, 29655.0, 29810.701892980793, 30116.09651710452, 42487.57512424836, 43352.0, 43397.34903976506, 43404.34893476663, 43422.0, 315650.0, 324161.0, 324229.0, 324987.0, 325210.0, 22737.0, 24879.129230476847, 25124.497510050063, 25431.87284063568, 25566.48867022612, 42432.78783606062, 42656.57343426526, 42695.0, 43443.56556434522, 43590.56409435865, 36140.0, 36422.8178859104, 37006.0, 37127.25745485129, 37561.624383755814, 36365.0, 36780.632193677724, 37029.62970370369, 37194.62805371912, 37254.627453725116, 36007.81996090108, 36014.0, 36352.27295454129, 36367.818160909024, 37584.624153758115, 32193.678063219068, 33960.66039339575, 34206.65793342035, 34222.0, 34624.0, 25585.616215756407, 25803.354916127246, 26208.475830483665, 26412.603810942466, 26741.331466713487], "value": 17270.46147814083, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.76702061211745, 375.981200939953, 376.60644626365445, 385.9652631263186, 389.6746216908881, 16645.667086658275, 16815.663686726275, 17562.736558951627, 17575.91212043938, 17576.47270581883, 16158.192090395492, 16503.752443713354, 16939.91530042348, 16996.915015424936, 17792.377266795665, 4623.838165664202, 4802.183628783097, 4840.9515904840855, 4843.4914333994775, 4844.37023186987, 3507.421275489541, 3699.2416554606243, 4753.144434001878, 5418.864528386802, 5740.311162660487, 5778.855528611797, 5790.305163380401, 5800.593958422912, 5801.216835727186, 5811.767529298823, 5506.284183056197, 5515.834524964257, 5517.282753242073, 5519.6964166971, 5522.944770552283, 3413.6245013048547, 5233.398159211702, 5359.598030147716, 5538.556915446755, 5553.7778488860395, 11357.829632555437, 11376.829347559793, 11398.0, 11447.484863181176, 19760.40718778439, 1864.6363959027994, 1903.9428817135508, 2026.574419371919, 2075.3462659262286, 2222.7221597300336, 9437.905620943771, 9642.228621710317, 9783.36408133469, 9836.31145819793, 10978.011978921855, 1605.8474444927713, 1681.630041390892, 1716.7939847218288, 1750.956226094345, 1779.1015537153655, 69199.65400172966, 71048.28951710468, 73019.0, 73316.0, 76189.0, 10073.899261007296, 10166.796664066676, 10373.792524149474, 10566.0, 10567.63013294531, 14343.282835858374, 14424.927875360556, 14429.711405771826, 14434.278286085864, 14453.205073721047, 6683.097781799492, 6845.965770171118, 6890.345417185359, 6898.206706228748, 6984.952257161365, 6306.527010474233, 6314.810555683245, 6315.084312774714, 6399.424051835264, 6423.164988551552, 4458.464984201916, 4487.663425243055, 4514.525974772667, 4518.051209246029, 4600.42494688164, 1663.6773764856725, 1678.3370568625326, 1750.859931205526, 1826.8264514871066, 1832.7196107955556, 819.3117781063935, 837.5770236030766, 844.4595458906305, 896.4442045931564, 920.2132176988712, 16574.917125414537, 16866.241019154168, 17430.91284543569, 18183.636327273645, 18245.0, 847.4872702015192, 873.7116751471999, 894.9194572488507, 906.909309069101, 927.3415874729018, 9760.316777825485, 10210.234232432596, 10217.284790064772, 10273.760691437856, 10365.481725913674, 383575.0, 390177.0, 400084.0, 400202.0, 400892.0, 78370.0, 79598.0, 80704.0, 81899.0, 82962.0, 904.9909500904906, 917.3991035871514, 931.1433481197315, 938.0525669074284, 945.9479728614855, 15771.526854194393, 15807.604809879846, 15824.60438489001, 16059.518214453585, 16442.34230630809, 38393.4240986391, 38494.42258366071, 38702.2259554813, 39034.80482597569, 39142.80428597839, 51289.743551282, 53417.46582534125, 53527.46472535225, 53540.73229633827, 53695.731521342146, 6478.28738838718, 6802.931970680428, 7074.327938845801, 7189.784306470814, 7238.443734596876, 8630.39587228888, 11032.0, 11368.374739389414, 11469.0, 11747.236429632047, 9828.31201815866, 9957.651482198089, 10111.646092387025, 10309.432981186106, 10449.477526123816, 9757.707268781947, 9782.75543111428, 10113.19094472455, 10115.190784737351, 10123.544440500047, 9996.0, 10032.297739158188, 10078.395296282246, 10449.477526123512, 10615.734606634587, 6847.726090956306, 7048.436125110082, 7062.964685176541, 7129.643517823984, 7153.964230179024, 7817.843643126992, 10186.592536298762, 10226.386416815016, 10293.43386113771, 10362.896371036193], "value": 8083.345767415128, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.9796453053202, 1400.04796738218, 1488.2838819155038, 1670.4237038221825, 1691.6024734187463, 7084.929150708492, 7492.063492063492, 7539.246075392465, 7539.547627142369, 7546.207648196929, 6379.744810207586, 7017.122859642544, 7190.53261538001, 7242.891356629655, 7386.889196662054, 2196.3410976707046, 2217.933461996143, 2218.0794970087395, 2221.8111460525874, 2222.2666520048424, 1845.7415961765364, 2189.3431970408815, 2203.636399993999, 2216.711827462436, 2218.545198234358, 2104.1478201328487, 2184.8252139828774, 2186.387811412799, 2198.5932602468515, 2202.856814307073, 2178.270279456384, 2186.7375914890235, 2196.2642514757576, 2206.8455208135438, 2225.4658881868395, 2177.4882902517897, 2183.257692384597, 2191.298784388996, 2197.846150769447, 2203.2068455356057, 7531.510451820669, 7537.660805263777, 7595.126560445564, 7596.658150383246, 7610.8477830443635, 4311.935320970188, 4457.576530229623, 4458.108378324316, 4482.39487669167, 4500.617447516965, 2742.2184677367054, 2838.8580570971367, 2885.0767754318626, 2886.6536015678253, 2900.4344152890194, 868.4441957147368, 874.8119154381775, 885.5970533407284, 885.8006948436615, 893.0578239956843, 9919.652812151613, 9970.202383809277, 9970.20238380935, 9976.152027077707, 9982.051705088077, 3946.2699400610977, 4055.0, 4146.087860670647, 4153.335466325373, 4193.391958166048, 8489.957550212333, 8541.103184165573, 8557.229849313591, 8571.100034496412, 8573.228409443056, 4583.19794036049, 4583.518730533313, 4614.2617181250835, 4615.353850460938, 4615.976920115378, 4161.542230354658, 4174.895627609334, 4191.7275377100395, 4220.240356735817, 4262.041040765865, 3503.8248087595516, 3531.7174626029855, 3532.6997205237844, 3576.8390422431053, 3601.729870259741, 1008.4453550547316, 1095.8191898336845, 1121.2039451989017, 1126.5242532282737, 1168.6727716239466, 727.1238158019573, 730.0399974034128, 733.1239169192763, 734.6693987705554, 759.9164091949881, 4842.539958703917, 4963.081829861487, 5023.6985780853265, 5035.571976381975, 5077.796888124508, 861.2550144125287, 862.6161358195668, 865.4634126841398, 866.0646501778065, 881.299367003234, 3343.398188326076, 3344.3311337732553, 3344.5317655528247, 3362.176266814646, 3471.1842716961496, 8898.4660920345, 9124.680636177834, 9248.213901818286, 9254.0283270257, 9263.351565390358, 7691.46159768822, 9131.315151363675, 9182.265418766483, 9206.723798286062, 9215.769605759775, 6653.567518111307, 6917.481188910851, 6991.965040174767, 7089.929100708927, 7111.608861512666, 5157.123289040797, 5157.303763991813, 5158.226266060121, 5348.705821179872, 5384.569234461155, 8312.335013198808, 8578.399512034348, 8599.312055035458, 8678.78303042429, 8680.869786953326, 8558.657653693783, 8574.82850343002, 8589.65641374363, 8659.653613855628, 8668.176523230282, 6445.903311450238, 6454.515911306765, 6455.3221911700475, 6487.351264873475, 6495.577787443801, 7110.4667149965, 7131.144262688511, 7156.427485801019, 7183.20984691673, 7244.673989670372, 6410.487161027013, 6542.771003014872, 6618.569792963442, 6680.899786503303, 6739.292374300628, 6511.674416279262, 6518.380753828379, 6541.182352205974, 6555.049517819794, 6605.372489613479, 5932.228810254639, 5959.731812068554, 5965.164876917322, 5992.550558708202, 6006.549508786946, 4300.763458009715, 4469.664775141812, 4492.989077457546, 4522.706024108423, 4557.339185817971, 4451.643868490448, 4487.282034874404, 4543.522930092293, 4568.81724731007, 4627.167109920247], "value": 4007.5676651420017, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.5793579716202, 293.43074435594946, 296.1840130440636, 303.3447752853835, 305.30238405243995, 4886.731229782361, 4913.631477639174, 4928.408590969079, 4938.901221975559, 4955.876103097425, 4885.975570122145, 4909.607231421487, 4912.656114072016, 4913.434954980178, 4916.532929371713, 3663.999999999993, 3783.186614877801, 3823.560290566593, 3885.048163200006, 3915.7846318452475, 3851.788151651658, 3872.244912242114, 3893.922121557567, 3899.6295351941662, 3901.824417901187, 3681.4661874028384, 3834.7153703509357, 3859.421086836983, 3861.1119442528234, 3872.9031774205587, 3769.6230376962358, 3845.807709614536, 3848.5381754189398, 3852.9807350963347, 3876.108495046127, 3432.982835085809, 3436.810975396377, 3443.9311213775586, 3450.292689998544, 3467.167879708877, 4791.257355109963, 4806.158922188638, 4813.085513752376, 4822.517748225185, 4833.275008748681, 4142.378643203513, 4348.7390756554705, 4354.869353919387, 4400.911981760347, 4499.820007199708, 5133.871653208662, 5134.255532947702, 5159.329287192679, 5161.793528258865, 5200.3759548854005, 3531.7351198659944, 3669.348793043141, 3675.8788569486296, 3695.981520092409, 3713.1274150574595, 5344.305240318734, 5364.651297665679, 5376.758045887945, 5380.623356365056, 5387.299651045339, 3695.6858667013603, 3697.8890633281044, 3700.5374328208973, 3701.9074523137137, 3719.497867787868, 3765.5669597996034, 3781.7352785305316, 3789.412641040643, 3807.276617442677, 3808.27642747877, 4145.590499230276, 4287.807048682755, 4292.055747735493, 4294.677899157576, 4358.193734159191, 5127.179651255781, 5134.127198376285, 5152.845414637567, 5160.200168973815, 5170.276161337416, 1301.1542497376781, 1321.6827961289414, 1324.2253281830044, 1325.6685828542863, 1326.085001349066, 842.9336888835628, 844.2950136635977, 862.2627653356416, 864.7535452396037, 871.5990644303564, 4401.0, 4511.751853648081, 4519.689290105833, 4526.932096018562, 4527.4340707411575, 5316.813911513156, 5328.014317351302, 5332.946670533245, 5341.839744807662, 5345.545628621533, 4354.629856462237, 4506.954930450719, 4515.683902126885, 4517.141743068806, 4561.384213131185, 2803.9859800700865, 2819.7039310872474, 2828.533292006837, 2832.589274555178, 2853.372258103214, 4965.361430727851, 4974.5771609413605, 4975.527324904201, 4996.575291100225, 4997.40083173381, 5213.921791173136, 5467.234587157801, 5478.205660179252, 5507.146392309199, 5516.531094856902, 5004.824831130893, 5016.799328026838, 5030.496950305013, 5042.899142017212, 5070.163423035232, 5374.919376209438, 5387.245785590099, 5414.891702165856, 5439.619226654097, 5451.672899626035, 5188.429272779916, 5259.47405259471, 5304.893902122013, 5305.257263983122, 5318.228856815817, 5016.54520189141, 5031.421386540448, 5044.192929131322, 5057.898842023065, 5121.948780512147, 3864.4976153099915, 3867.974986628602, 3875.2830726315724, 3882.0, 3886.84452621892, 5123.948760512347, 5358.356997160366, 5381.381141168816, 5443.210734443563, 5456.61803673739, 5172.948270517397, 5181.430042695376, 5202.479752024768, 5242.764075616683, 5269.657472264291, 5141.323953373356, 5231.372235331784, 5253.474652534869, 5258.342707161605, 5281.445448228034, 4904.485029071896, 5162.277281180563, 5170.612204084633, 5171.974140129275, 5175.896482070262, 5044.54599086084, 5222.67590922036, 5241.921371179359, 5244.9737751310995, 5318.361796584435, 5164.302819119371, 5174.637775355689, 5178.0, 5179.352580927384, 5185.870353241048], "value": 3898.6751834713777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.2155335151169, 660.7423104989053, 702.9472789540782, 723.424877222608, 741.7250064454713, 12109.333986630743, 12951.870481295184, 12976.480940762358, 12980.480780768758, 12986.610401687953, 12566.24602523847, 12619.43212555435, 12635.747285054293, 12642.683932901682, 12651.62045138645, 625.5933643131978, 765.9157492675802, 769.6113462701356, 787.6494959742917, 795.4630624328584, 774.2915232562227, 775.8099265679916, 777.6189667063155, 778.8792737125755, 781.5896654256504, 837.5100566168799, 840.3025488844245, 841.1168273313027, 841.221869770463, 841.734853521142, 660.6762686283711, 679.5684740189965, 682.1643486728767, 682.6552590941543, 835.340081335744, 596.9701514924236, 602.9005214139705, 608.5010291560922, 609.6250805754473, 609.6524980760968, 12652.936735316356, 12728.872711272954, 12752.362381880961, 12766.872331276661, 12774.850263476239, 12542.937285313606, 12698.365081745877, 12834.229946203164, 12981.415836287295, 13039.152455090483, 11679.532818687243, 11725.472353744102, 11738.823917641243, 11944.402779861059, 12211.0, 4204.936925946114, 4217.641500472463, 4285.400043993844, 4306.0, 4418.3372494125815, 58900.41099588992, 59509.40490595082, 59522.0, 59808.70095649538, 59816.40183598195, 10545.367277963192, 10661.626843060456, 10691.411972341595, 10799.136069114453, 10858.619948301772, 19280.32518861833, 19290.131944062552, 19294.22823087089, 19317.61364772697, 19328.903355483413, 6420.678966051679, 6442.387973142544, 6504.089427480157, 6510.21877374718, 6694.665266736741, 3784.6404591563755, 3810.371288737327, 3825.6556909878245, 3846.538415390171, 3862.7682339059747, 2690.0450340129364, 2782.7912906531687, 2783.819051761629, 2786.247713117447, 2787.6933537311284, 462.8727100047514, 466.7059752355994, 470.4025887123403, 472.1713392995271, 478.930555069513, 524.7481209019692, 542.734060310445, 578.6730497269114, 582.973766180523, 599.6551982610017, 7718.379677786973, 8040.0, 8159.632816523271, 8189.426740128253, 8448.66205351779, 538.9703566303891, 551.4402881075712, 570.6861226325587, 588.0561698473973, 611.788932818177, 3832.9616703832603, 3871.806409679561, 3950.6839452843706, 3986.342253528135, 4124.52567954689, 82412.58793705993, 84875.0, 86118.0, 87738.56130719306, 93963.53018235002, 75115.62442187863, 77669.61165194138, 81123.59438202772, 81303.18696813074, 82309.0, 43134.78432607817, 43993.0, 68298.65850670714, 70337.64831175914, 72795.0, 9201.263898887939, 9240.168384845396, 9480.431174129299, 9510.809783804423, 9570.712878613653, 38496.2300753989, 42308.57691423046, 43506.56493435025, 45131.7743411294, 45166.0, 58940.0, 59122.0, 60591.69704151451, 61577.69211153915, 61589.384106160156, 11527.423628818358, 11936.462859171184, 12072.0, 12193.634190974284, 12557.309347985949, 12696.619101426972, 12803.0, 12831.679208019874, 13483.528076517667, 13625.386857591631, 12283.754324913272, 12371.0, 12472.0, 13043.347832608142, 13049.869501304865, 12558.246505209716, 12572.622821315375, 12620.55828046014, 12831.10182287231, 13407.396667150197, 10820.458977051274, 11823.290602563873, 11855.644330670093, 11905.642830715093, 11944.283342999448, 9874.901250987397, 9890.159336456481, 10261.384316941007, 10317.9484102579, 10458.267921245744, 9287.582058807235, 9297.495906386059, 9543.284253680862, 10134.586754432723, 10217.182625389802], "value": 6550.238324653328, "unit": "score"}], "name": "dromaeo_css", "value": 6381.935508078513, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 114.0, 115.0, 117.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 124.0, 170.0, 122.0, 124.0, 147.0, 121.0, 168.0, 122.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 162.0, 161.0, 162.0, 169.0, 160.0, 161.0, 163.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 119.0, 76.0, 77.0, 75.0, 75.0, 110.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 126.0, 85.0, 85.0, 84.0, 118.0, 84.0, 85.0, 130.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 148.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 71.0, 64.0, 63.0, 67.0, 63.0, 63.0, 62.0, 63.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 69.0, 65.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 79.0, 78.0, 85.0, 78.0, 79.0, 79.0, 79.0, 80.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 134.0, 132.0, 140.0, 133.0, 129.0, 129.0, 128.0, 170.0, 148.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 169.0, 173.0, 174.0, 172.0, 170.0, 165.0, 167.0, 168.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 68.0, 66.0, 67.0, 69.0, 66.0, 66.0, 65.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1535.8999999999999, "alertThreshold": 2.0}]} 15:37:28 INFO - Return code: 0 15:37:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:37:28 INFO - # TBPL SUCCESS # 15:37:28 INFO - Running post-action listener: _resource_record_post_action 15:37:28 INFO - ##### 15:37:28 INFO - ##### Finished run-tests step (success) 15:37:28 INFO - ##### 15:37:28 INFO - Running post-run listener: _resource_record_post_run 15:37:28 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 542494720; Read time: 0; Write time: 428140 15:37:28 INFO - TinderboxPrint: CPU usage
13.0% 15:37:28 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:37:28 INFO - TinderboxPrint: I/O write bytes / time
542,494,720 / 428,140 15:37:28 INFO - TinderboxPrint: CPU idle
8,328.9 (87.0%) 15:37:28 INFO - TinderboxPrint: CPU user
1,201.0 (12.5%) 15:37:28 INFO - TinderboxPrint: Swap in / out
0 / 0 15:37:28 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254771200; Read time: 0; Write time: 205404 15:37:28 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 287723520; Read time: 0; Write time: 222736 15:37:28 INFO - Running post-run listener: _upload_blobber_files 15:37:28 INFO - Blob upload gear active. 15:37:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:28 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 15:37:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:37:29 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:37:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:37:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:33 INFO - (blobuploader) - INFO - Done attempting. 15:37:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:37:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:37:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:34 INFO - (blobuploader) - INFO - Done attempting. 15:37:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:37:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:37:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:35 INFO - (blobuploader) - INFO - Done attempting. 15:37:35 INFO - (blobuploader) - INFO - Iteration through files over. 15:37:35 INFO - Return code: 0 15:37:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:37:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:37:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc39e7510ea0d8764be5eced4b837073fd7881ed25db68e7032303fb88eb565cd387b1a5fe656710f0e5f37182bff4b3fd4133cdd77cc2df4382ea1a8ff6f81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ee6dc642a4757b0ea96408980f6570c6525cba466e6490d8ef99263a4d13ad157eab09a85706888857fe8b3a10e58dbd369c54db5f3a62579ed6eed07de3f7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/618b690063d962a7fed60dffe68b24fedbc1285dd68382bedd06ce79bb75d0494696767959474fe8adae7a33e31ff6e78ccae53250139533557e2aa2ffe9d344"} 15:37:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:37:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:37:35 INFO - Contents: 15:37:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc39e7510ea0d8764be5eced4b837073fd7881ed25db68e7032303fb88eb565cd387b1a5fe656710f0e5f37182bff4b3fd4133cdd77cc2df4382ea1a8ff6f81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ee6dc642a4757b0ea96408980f6570c6525cba466e6490d8ef99263a4d13ad157eab09a85706888857fe8b3a10e58dbd369c54db5f3a62579ed6eed07de3f7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/618b690063d962a7fed60dffe68b24fedbc1285dd68382bedd06ce79bb75d0494696767959474fe8adae7a33e31ff6e78ccae53250139533557e2aa2ffe9d344"} 15:37:35 INFO - Running post-run listener: copy_logs_to_upload_dir 15:37:35 INFO - Copying logs to upload dir... 15:37:35 INFO - mkdir: /builds/slave/test/build/upload/logs 15:37:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.778314 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-11 15:37:34.430533) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 15:37:34.434419) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc39e7510ea0d8764be5eced4b837073fd7881ed25db68e7032303fb88eb565cd387b1a5fe656710f0e5f37182bff4b3fd4133cdd77cc2df4382ea1a8ff6f81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ee6dc642a4757b0ea96408980f6570c6525cba466e6490d8ef99263a4d13ad157eab09a85706888857fe8b3a10e58dbd369c54db5f3a62579ed6eed07de3f7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/618b690063d962a7fed60dffe68b24fedbc1285dd68382bedd06ce79bb75d0494696767959474fe8adae7a33e31ff6e78ccae53250139533557e2aa2ffe9d344"} build_url:https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/WXJTTgHKTi2JH2iR67V7IQ/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-Non-PGO/sha512/3fc39e7510ea0d8764be5eced4b837073fd7881ed25db68e7032303fb88eb565cd387b1a5fe656710f0e5f37182bff4b3fd4133cdd77cc2df4382ea1a8ff6f81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ee6dc642a4757b0ea96408980f6570c6525cba466e6490d8ef99263a4d13ad157eab09a85706888857fe8b3a10e58dbd369c54db5f3a62579ed6eed07de3f7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/618b690063d962a7fed60dffe68b24fedbc1285dd68382bedd06ce79bb75d0494696767959474fe8adae7a33e31ff6e78ccae53250139533557e2aa2ffe9d344"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 15:37:34.459870) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:37:34.460178) ========= 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=20e666a3d4cfbb71505a330e000001b4-1468275415.837148-30333654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004397 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:37:34.543597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 15:37:34.543891) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 15:37:34.544163) ========= ========= Total master_lag: 0.18 =========