builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1468840853.91 results: success (0) buildid: 20160718013034 builduid: c3835165731c47c88ef94688b744dc59 revision: c8ba6a6eec22033228aad26f08db5ac4ff129461 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:53.906303) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:53.907029) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:53.907340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:53.971172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:53.971558) ========= 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=fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 04:20:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-07-18 04:20:57 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108863 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.094227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.094550) ========= 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=fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015600 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.144667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.145007) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c8ba6a6eec22033228aad26f08db5ac4ff129461 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c8ba6a6eec22033228aad26f08db5ac4ff129461 --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=fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 04:20:57,613 truncating revision to first 12 chars 2016-07-18 04:20:57,613 Setting DEBUG logging. 2016-07-18 04:20:57,613 attempt 1/10 2016-07-18 04:20:57,614 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c8ba6a6eec22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 04:20:57,935 unpacking tar archive at: mozilla-inbound-c8ba6a6eec22/testing/mozharness/ program finished with exit code 0 elapsedTime=0.673281 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.831953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.832279) ========= script_repo_revision: c8ba6a6eec22033228aad26f08db5ac4ff129461 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.832680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.832936) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 04:20:54.842667) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-18 04:20:54.843122) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:20:58 INFO - MultiFileLogger online at 20160718 04:20:58 in /builds/slave/test 04:20:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 04:20:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:20:58 INFO - {'append_to_log': False, 04:20:58 INFO - 'base_work_dir': '/builds/slave/test', 04:20:58 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:20:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:20:58 INFO - 'branch': 'Mozilla-Inbound', 04:20:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:20:58 INFO - 'config_files': ('talos/linux_config.py',), 04:20:58 INFO - 'default_actions': ('clobber', 04:20:58 INFO - 'read-buildbot-config', 04:20:58 INFO - 'download-and-extract', 04:20:58 INFO - 'populate-webroot', 04:20:58 INFO - 'create-virtualenv', 04:20:58 INFO - 'install', 04:20:58 INFO - 'run-tests'), 04:20:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:20:58 INFO - 'download_minidump_stackwalk': True, 04:20:58 INFO - 'download_symbols': 'ondemand', 04:20:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:20:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:20:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:20:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:20:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:20:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:20:58 INFO - 'installer_path': 'installer.exe', 04:20:58 INFO - 'log_level': 'info', 04:20:58 INFO - 'log_name': 'talos', 04:20:58 INFO - 'log_to_console': True, 04:20:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:20:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:20:58 INFO - 'opt_config_files': (), 04:20:58 INFO - 'pip_index': False, 04:20:58 INFO - 'sps_profile': False, 04:20:58 INFO - 'sps_profile_interval': 0, 04:20:58 INFO - 'suite': 'dromaeojs', 04:20:58 INFO - 'system_bits': '32', 04:20:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:20:58 INFO - 'title': 'talos-linux64-ix-048', 04:20:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:20:58 INFO - 'use_talos_json': True, 04:20:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:20:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:20:58 INFO - 'work_dir': 'build'} 04:20:58 INFO - ##### 04:20:58 INFO - ##### Running clobber step. 04:20:58 INFO - ##### 04:20:58 INFO - Running pre-action listener: _resource_record_pre_action 04:20:58 INFO - Running main action method: clobber 04:20:58 INFO - rmtree: /builds/slave/test/build 04:20:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:20:59 INFO - Running post-action listener: _resource_record_post_action 04:20:59 INFO - ##### 04:20:59 INFO - ##### Finished clobber step (success) 04:20:59 INFO - ##### 04:20:59 INFO - ##### 04:20:59 INFO - ##### Running read-buildbot-config step. 04:20:59 INFO - ##### 04:20:59 INFO - Running pre-action listener: _resource_record_pre_action 04:20:59 INFO - Running main action method: read_buildbot_config 04:20:59 INFO - Using buildbot properties: 04:20:59 INFO - { 04:20:59 INFO - "project": "", 04:20:59 INFO - "product": "firefox", 04:20:59 INFO - "script_repo_revision": "production", 04:20:59 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:20:59 INFO - "repository": "", 04:20:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:20:59 INFO - "buildid": "20160718013034", 04:20:59 INFO - "pgo_build": "True", 04:20:59 INFO - "basedir": "/builds/slave/test", 04:20:59 INFO - "buildnumber": 1256, 04:20:59 INFO - "slavename": "talos-linux64-ix-048", 04:20:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:20:59 INFO - "platform": "ubuntu64_hw", 04:20:59 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:20:59 INFO - "branch": "mozilla-inbound", 04:20:59 INFO - "repo_path": "integration/mozilla-inbound", 04:20:59 INFO - "revision": "c8ba6a6eec22033228aad26f08db5ac4ff129461", 04:20:59 INFO - "stage_platform": "linux64-pgo", 04:20:59 INFO - "builduid": "c3835165731c47c88ef94688b744dc59", 04:20:59 INFO - "slavebuilddir": "test" 04:20:59 INFO - } 04:20:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:20:59 INFO - Running post-action listener: _resource_record_post_action 04:20:59 INFO - ##### 04:20:59 INFO - ##### Finished read-buildbot-config step (success) 04:20:59 INFO - ##### 04:20:59 INFO - ##### 04:20:59 INFO - ##### Running download-and-extract step. 04:20:59 INFO - ##### 04:20:59 INFO - Running pre-action listener: _resource_record_pre_action 04:20:59 INFO - Running main action method: download_and_extract 04:20:59 INFO - mkdir: /builds/slave/test/build/tests 04:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:20:59 INFO - https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:20:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:20:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:20:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:20:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:00 INFO - Downloaded 1361 bytes. 04:21:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:21:00 INFO - Using the following test package requirements: 04:21:00 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:21:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:21:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:21:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'jsshell-linux-x86_64.zip'], 04:21:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:21:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:21:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:21:00 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:21:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:21:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:21:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:21: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')]} 04:21:00 INFO - https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:21:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:21:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:21:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:01 INFO - Downloaded 24334383 bytes. 04:21:01 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'] 04:21:01 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 04:21:02 INFO - Return code: 0 04:21:02 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 04:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:21:02 INFO - https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:21:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:21:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:21:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:03 INFO - Downloaded 24334383 bytes. 04:21:03 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'] 04:21:03 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 04:21:04 INFO - Return code: 0 04:21: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')]} 04:21:04 INFO - https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:21:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:21:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:21:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:05 INFO - Downloaded 14403317 bytes. 04:21:05 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'] 04:21:05 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 04:21:06 INFO - Return code: 0 04:21:06 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')]} 04:21:06 INFO - https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:21:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:21:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:21:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DRsE8QyhQp-Diue66PX13g/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 04:21:07 INFO - Downloaded 60834016 bytes. 04:21:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:21:07 INFO - mkdir: /builds/slave/test/properties 04:21:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:21:07 INFO - Writing to file /builds/slave/test/properties/build_url 04:21:07 INFO - Contents: 04:21:07 INFO - build_url:https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:21:08 INFO - Running post-action listener: _resource_record_post_action 04:21:08 INFO - Running post-action listener: set_extra_try_arguments 04:21:08 INFO - ##### 04:21:08 INFO - ##### Finished download-and-extract step (success) 04:21:08 INFO - ##### 04:21:08 INFO - ##### 04:21:08 INFO - ##### Running populate-webroot step. 04:21:08 INFO - ##### 04:21:08 INFO - Running pre-action listener: _resource_record_pre_action 04:21:08 INFO - Running main action method: populate_webroot 04:21:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:21:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:21:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:21:08 INFO - '--tppagecycles', 04:21:08 INFO - '7'], 04:21:08 INFO - 'tests': ['tsvgm']}}, 04:21:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:21:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:21:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:21:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:21:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['damp', 'tps']}, 04:21:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'tests': ['damp', 'tps']}, 04:21:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['dromaeo_dom']}, 04:21:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:21:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['basic_compositor_video']}, 04:21:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:21:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['a11yr', 04:21:08 INFO - 'ts_paint', 04:21:08 INFO - 'tpaint', 04:21:08 INFO - 'sessionrestore', 04:21:08 INFO - 'sessionrestore_no_auto_restore', 04:21:08 INFO - 'tabpaint']}, 04:21:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:21:08 INFO - 'ts_paint', 04:21:08 INFO - 'tpaint', 04:21:08 INFO - 'sessionrestore', 04:21:08 INFO - 'sessionrestore_no_auto_restore', 04:21:08 INFO - 'tabpaint']}, 04:21:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:21:08 INFO - 'ts_paint', 04:21:08 INFO - 'tpaint', 04:21:08 INFO - 'sessionrestore', 04:21:08 INFO - 'sessionrestore_no_auto_restore', 04:21:08 INFO - 'tabpaint']}, 04:21:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:21:08 INFO - 'ts_paint', 04:21:08 INFO - 'tpaint', 04:21:08 INFO - 'sessionrestore', 04:21:08 INFO - 'sessionrestore_no_auto_restore', 04:21:08 INFO - 'tabpaint']}, 04:21:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['a11yr', 04:21:08 INFO - 'ts_paint', 04:21:08 INFO - 'tpaint', 04:21:08 INFO - 'sessionrestore', 04:21:08 INFO - 'sessionrestore_no_auto_restore', 04:21:08 INFO - 'tabpaint']}, 04:21:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['a11yr', 04:21:08 INFO - 'ts_paint', 04:21:08 INFO - 'tpaint', 04:21:08 INFO - 'sessionrestore', 04:21:08 INFO - 'sessionrestore_no_auto_restore', 04:21:08 INFO - 'tabpaint']}, 04:21:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['tsvgx', 04:21:08 INFO - 'tsvgr_opacity', 04:21:08 INFO - 'tart', 04:21:08 INFO - 'tscrollx', 04:21:08 INFO - 'cart']}, 04:21:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:21:08 INFO - 'tsvgr_opacity', 04:21:08 INFO - 'tart', 04:21:08 INFO - 'tscrollx', 04:21:08 INFO - 'cart']}, 04:21:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'talos_options': ['--disable-e10s'], 04:21:08 INFO - 'tests': ['tp5o']}, 04:21:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'tests': ['tp5o']}, 04:21:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'talos_options': ['--disable-e10s', 04:21:08 INFO - '--xperf_path', 04:21:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:08 INFO - 'tests': ['tp5n']}, 04:21:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:08 INFO - 'talos_options': ['--xperf_path', 04:21:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:08 INFO - 'tests': ['tp5n']}}, 04:21:08 INFO - 'talos.zip': {'path': '', 04:21:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:21:08 INFO - Running post-action listener: _resource_record_post_action 04:21:08 INFO - ##### 04:21:08 INFO - ##### Finished populate-webroot step (success) 04:21:08 INFO - ##### 04:21:08 INFO - ##### 04:21:08 INFO - ##### Running create-virtualenv step. 04:21:08 INFO - ##### 04:21:08 INFO - Running pre-action listener: _resource_record_pre_action 04:21:08 INFO - Running main action method: create_virtualenv 04:21:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:21:08 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 04:21:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:21:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:21:08 INFO - Using real prefix '/usr' 04:21:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:21:08 INFO - Installing distribute.............................................................................................................................................................................................done. 04:21:10 INFO - Installing pip.................done. 04:21:10 INFO - Return code: 0 04:21:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:10 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:10 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 04:21:10 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 04:21:10 INFO - Using env: {'DISPLAY': ':0', 04:21:10 INFO - 'HOME': '/home/cltbld', 04:21:10 INFO - 'LANG': 'en_US.UTF-8', 04:21:10 INFO - 'LANGUAGE': 'en_US:en', 04:21:10 INFO - 'LOGNAME': 'cltbld', 04:21:10 INFO - 'MAIL': '/var/mail/cltbld', 04:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:10 INFO - 'MOZ_NO_REMOTE': '1', 04:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:10 INFO - 'NO_EM_RESTART': '1', 04:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:10 INFO - 'PWD': '/builds/slave/test', 04:21:10 INFO - 'SHELL': '/bin/bash', 04:21:10 INFO - 'SHLVL': '1', 04:21:10 INFO - 'TERM': 'linux', 04:21:10 INFO - 'TMOUT': '86400', 04:21:10 INFO - 'USER': 'cltbld', 04:21:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:10 INFO - '_': '/tools/buildbot/bin/python'} 04:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:11 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 04:21:11 INFO - Cleaning up... 04:21:11 INFO - Return code: 0 04:21:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:11 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:11 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 04:21:11 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 04:21:11 INFO - Using env: {'DISPLAY': ':0', 04:21:11 INFO - 'HOME': '/home/cltbld', 04:21:11 INFO - 'LANG': 'en_US.UTF-8', 04:21:11 INFO - 'LANGUAGE': 'en_US:en', 04:21:11 INFO - 'LOGNAME': 'cltbld', 04:21:11 INFO - 'MAIL': '/var/mail/cltbld', 04:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:11 INFO - 'MOZ_NO_REMOTE': '1', 04:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:11 INFO - 'NO_EM_RESTART': '1', 04:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:11 INFO - 'PWD': '/builds/slave/test', 04:21:11 INFO - 'SHELL': '/bin/bash', 04:21:11 INFO - 'SHLVL': '1', 04:21:11 INFO - 'TERM': 'linux', 04:21:11 INFO - 'TMOUT': '86400', 04:21:11 INFO - 'USER': 'cltbld', 04:21:11 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:11 INFO - '_': '/tools/buildbot/bin/python'} 04:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:11 INFO - Downloading/unpacking psutil>=3.1.1 04:21:11 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 04:21:11 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 04:21:11 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 04:21:11 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 04:21:11 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 04:21:11 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 04:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:21:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:13 INFO - Installing collected packages: psutil 04:21:13 INFO - Running setup.py install for psutil 04:21:13 INFO - building 'psutil._psutil_linux' extension 04:21:13 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 04:21:13 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 04:21:13 INFO - building 'psutil._psutil_posix' extension 04:21:13 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 04:21:13 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 04:21:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:14 INFO - Successfully installed psutil 04:21:14 INFO - Cleaning up... 04:21:14 INFO - Return code: 0 04:21:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:21: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')]} 04:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21: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')]} 04:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:21: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 mozsystemmonitor==0.3 04:21:14 INFO - Using env: {'DISPLAY': ':0', 04:21:14 INFO - 'HOME': '/home/cltbld', 04:21:14 INFO - 'LANG': 'en_US.UTF-8', 04:21:14 INFO - 'LANGUAGE': 'en_US:en', 04:21:14 INFO - 'LOGNAME': 'cltbld', 04:21:14 INFO - 'MAIL': '/var/mail/cltbld', 04:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:14 INFO - 'MOZ_NO_REMOTE': '1', 04:21:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:14 INFO - 'NO_EM_RESTART': '1', 04:21:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:14 INFO - 'PWD': '/builds/slave/test', 04:21:14 INFO - 'SHELL': '/bin/bash', 04:21:14 INFO - 'SHLVL': '1', 04:21:14 INFO - 'TERM': 'linux', 04:21:14 INFO - 'TMOUT': '86400', 04:21:14 INFO - 'USER': 'cltbld', 04:21:14 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:14 INFO - '_': '/tools/buildbot/bin/python'} 04:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:21:14 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 04:21:14 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 04:21:14 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 04:21:14 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 04:21:14 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 04:21:14 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 04:21:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:21:16 INFO - Installing collected packages: mozsystemmonitor 04:21:16 INFO - Running setup.py install for mozsystemmonitor 04:21:16 INFO - Successfully installed mozsystemmonitor 04:21:16 INFO - Cleaning up... 04:21:16 INFO - Return code: 0 04:21:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:16 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:16 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 04:21:16 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 04:21:16 INFO - Using env: {'DISPLAY': ':0', 04:21:16 INFO - 'HOME': '/home/cltbld', 04:21:16 INFO - 'LANG': 'en_US.UTF-8', 04:21:16 INFO - 'LANGUAGE': 'en_US:en', 04:21:16 INFO - 'LOGNAME': 'cltbld', 04:21:16 INFO - 'MAIL': '/var/mail/cltbld', 04:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:16 INFO - 'MOZ_NO_REMOTE': '1', 04:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:16 INFO - 'NO_EM_RESTART': '1', 04:21:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:16 INFO - 'PWD': '/builds/slave/test', 04:21:16 INFO - 'SHELL': '/bin/bash', 04:21:16 INFO - 'SHLVL': '1', 04:21:16 INFO - 'TERM': 'linux', 04:21:16 INFO - 'TMOUT': '86400', 04:21:16 INFO - 'USER': 'cltbld', 04:21:16 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:16 INFO - '_': '/tools/buildbot/bin/python'} 04:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:21:16 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 04:21:16 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 04:21:16 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 04:21:16 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 04:21:16 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 04:21:16 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 04:21:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:21:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 INFO - Downloading functools32-3.2.3-2.tar.gz 04:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:21:18 INFO - warning: no files found matching '*.txt' 04:21:18 INFO - no previously-included directories found matching 'build' 04:21:18 INFO - no previously-included directories found matching 'dist' 04:21:18 INFO - no previously-included directories found matching '.git*' 04:21:18 INFO - Installing collected packages: jsonschema, functools32 04:21:18 INFO - Running setup.py install for functools32 04:21:18 INFO - warning: no files found matching '*.txt' 04:21:18 INFO - no previously-included directories found matching 'build' 04:21:18 INFO - no previously-included directories found matching 'dist' 04:21:18 INFO - no previously-included directories found matching '.git*' 04:21:18 INFO - Successfully installed jsonschema functools32 04:21:18 INFO - Cleaning up... 04:21:19 INFO - Return code: 0 04:21:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:19 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:19 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 04:21:19 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 04:21:19 INFO - Using env: {'DISPLAY': ':0', 04:21:19 INFO - 'HOME': '/home/cltbld', 04:21:19 INFO - 'LANG': 'en_US.UTF-8', 04:21:19 INFO - 'LANGUAGE': 'en_US:en', 04:21:19 INFO - 'LOGNAME': 'cltbld', 04:21:19 INFO - 'MAIL': '/var/mail/cltbld', 04:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:19 INFO - 'MOZ_NO_REMOTE': '1', 04:21:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:19 INFO - 'NO_EM_RESTART': '1', 04:21:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:19 INFO - 'PWD': '/builds/slave/test', 04:21:19 INFO - 'SHELL': '/bin/bash', 04:21:19 INFO - 'SHLVL': '1', 04:21:19 INFO - 'TERM': 'linux', 04:21:19 INFO - 'TMOUT': '86400', 04:21:19 INFO - 'USER': 'cltbld', 04:21:19 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:19 INFO - '_': '/tools/buildbot/bin/python'} 04:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:19 INFO - Downloading/unpacking blobuploader==1.2.4 04:21: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 04:21: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 04:21: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 04:21: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 04:21: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 04:21: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 04:21:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:21:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:21:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 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 04:21:21 INFO - Downloading docopt-0.6.1.tar.gz 04:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:21:21 INFO - Installing collected packages: blobuploader, requests, docopt 04:21:21 INFO - Running setup.py install for blobuploader 04:21:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:21:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:21:22 INFO - Running setup.py install for requests 04:21:22 INFO - Running setup.py install for docopt 04:21:22 INFO - Successfully installed blobuploader requests docopt 04:21:22 INFO - Cleaning up... 04:21:22 INFO - Return code: 0 04:21:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:22 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:22 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 04:21:22 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 04:21:22 INFO - Using env: {'DISPLAY': ':0', 04:21:22 INFO - 'HOME': '/home/cltbld', 04:21:22 INFO - 'LANG': 'en_US.UTF-8', 04:21:22 INFO - 'LANGUAGE': 'en_US:en', 04:21:22 INFO - 'LOGNAME': 'cltbld', 04:21:22 INFO - 'MAIL': '/var/mail/cltbld', 04:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:22 INFO - 'MOZ_NO_REMOTE': '1', 04:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:22 INFO - 'NO_EM_RESTART': '1', 04:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:22 INFO - 'PWD': '/builds/slave/test', 04:21:22 INFO - 'SHELL': '/bin/bash', 04:21:22 INFO - 'SHLVL': '1', 04:21:22 INFO - 'TERM': 'linux', 04:21:22 INFO - 'TMOUT': '86400', 04:21:22 INFO - 'USER': 'cltbld', 04:21:22 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:22 INFO - '_': '/tools/buildbot/bin/python'} 04:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:22 INFO - Running setup.py (path:/tmp/pip-ZQmeqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:22 INFO - Running setup.py (path:/tmp/pip-HNC1zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:22 INFO - Running setup.py (path:/tmp/pip-xHhjbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:22 INFO - Running setup.py (path:/tmp/pip-jZvK7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:23 INFO - Running setup.py (path:/tmp/pip-ZkC9wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:23 INFO - Running setup.py (path:/tmp/pip-pCFbC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:23 INFO - Running setup.py (path:/tmp/pip-Fw62LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:23 INFO - Running setup.py (path:/tmp/pip-f4oZRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:23 INFO - Running setup.py (path:/tmp/pip-_yDXOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:23 INFO - Running setup.py (path:/tmp/pip-icfvIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:23 INFO - Running setup.py (path:/tmp/pip-sOyQcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:23 INFO - Running setup.py (path:/tmp/pip-CU5QPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:23 INFO - Running setup.py (path:/tmp/pip-6OrckA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:23 INFO - Running setup.py (path:/tmp/pip-dmcWCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:23 INFO - Running setup.py (path:/tmp/pip-53I0iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:23 INFO - Running setup.py (path:/tmp/pip-MsJ_qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:23 INFO - Running setup.py (path:/tmp/pip-sfVqVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:21:23 INFO - Running setup.py install for manifestparser 04:21:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Running setup.py install for mozcrash 04:21:24 INFO - Running setup.py install for mozdebug 04:21:24 INFO - Running setup.py install for mozdevice 04:21:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Running setup.py install for mozfile 04:21:24 INFO - Running setup.py install for mozhttpd 04:21:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Running setup.py install for mozinfo 04:21:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Running setup.py install for mozInstall 04:21:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:21:24 INFO - Running setup.py install for mozleak 04:21:24 INFO - Running setup.py install for mozlog 04:21:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Running setup.py install for moznetwork 04:21:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Running setup.py install for mozprocess 04:21:25 INFO - Running setup.py install for mozprofile 04:21:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Running setup.py install for mozrunner 04:21:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Running setup.py install for mozscreenshot 04:21:25 INFO - Running setup.py install for moztest 04:21:25 INFO - Running setup.py install for mozversion 04:21:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:21:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:21:25 INFO - Cleaning up... 04:21:25 INFO - Return code: 0 04:21:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:25 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')]} 04:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:25 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')]} 04:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:25 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:25 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 04:21:25 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 04:21:25 INFO - Using env: {'DISPLAY': ':0', 04:21:25 INFO - 'HOME': '/home/cltbld', 04:21:25 INFO - 'LANG': 'en_US.UTF-8', 04:21:25 INFO - 'LANGUAGE': 'en_US:en', 04:21:25 INFO - 'LOGNAME': 'cltbld', 04:21:25 INFO - 'MAIL': '/var/mail/cltbld', 04:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:25 INFO - 'MOZ_NO_REMOTE': '1', 04:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:25 INFO - 'NO_EM_RESTART': '1', 04:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:25 INFO - 'PWD': '/builds/slave/test', 04:21:25 INFO - 'SHELL': '/bin/bash', 04:21:25 INFO - 'SHLVL': '1', 04:21:25 INFO - 'TERM': 'linux', 04:21:25 INFO - 'TMOUT': '86400', 04:21:25 INFO - 'USER': 'cltbld', 04:21:25 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:25 INFO - '_': '/tools/buildbot/bin/python'} 04:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:25 INFO - Running setup.py (path:/tmp/pip-6S7Xdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:26 INFO - Running setup.py (path:/tmp/pip-MXuH_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:26 INFO - Running setup.py (path:/tmp/pip-_Oz8Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:26 INFO - Running setup.py (path:/tmp/pip-xRBeAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:26 INFO - Running setup.py (path:/tmp/pip-oCjiax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:26 INFO - Running setup.py (path:/tmp/pip-jcqkPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:26 INFO - Running setup.py (path:/tmp/pip-THUAi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:26 INFO - Running setup.py (path:/tmp/pip-8iUuP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:26 INFO - Running setup.py (path:/tmp/pip-z6LXdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:26 INFO - Running setup.py (path:/tmp/pip-riLeJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:26 INFO - Running setup.py (path:/tmp/pip-5ggS8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:26 INFO - Running setup.py (path:/tmp/pip-8DuHHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:26 INFO - Running setup.py (path:/tmp/pip-edrwaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:26 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)) 04:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:26 INFO - Running setup.py (path:/tmp/pip-hYKXpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:27 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)) 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:27 INFO - Running setup.py (path:/tmp/pip-_FHGCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:27 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)) 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:27 INFO - Running setup.py (path:/tmp/pip-IrSw2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:27 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)) 04:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:27 INFO - Running setup.py (path:/tmp/pip-jQ3YZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:27 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)) 04:21:27 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)) 04:21:27 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)) 04:21:27 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)) 04:21:27 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)) 04:21:27 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)) 04:21:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:21:27 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 04:21:27 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 04:21:27 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 04:21:27 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 04:21:27 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 04:21:27 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 04:21:29 INFO - Downloading blessings-1.6.tar.gz 04:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:21:29 INFO - Installing collected packages: blessings 04:21:29 INFO - Running setup.py install for blessings 04:21:29 INFO - Successfully installed blessings 04:21:29 INFO - Cleaning up... 04:21:29 INFO - Return code: 0 04:21:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:29 INFO - Reading from file tmpfile_stdout 04:21:29 INFO - Current package versions: 04:21:29 INFO - argparse == 1.2.1 04:21:29 INFO - blessings == 1.6 04:21:29 INFO - blobuploader == 1.2.4 04:21:29 INFO - docopt == 0.6.1 04:21:29 INFO - functools32 == 3.2.3-2 04:21:29 INFO - jsonschema == 2.5.1 04:21:29 INFO - manifestparser == 1.1 04:21:29 INFO - mozInstall == 1.12 04:21:29 INFO - mozcrash == 0.17 04:21:29 INFO - mozdebug == 0.1 04:21:29 INFO - mozdevice == 0.48 04:21:29 INFO - mozfile == 1.2 04:21:29 INFO - mozhttpd == 0.7 04:21:29 INFO - mozinfo == 0.9 04:21:29 INFO - mozleak == 0.1 04:21:29 INFO - mozlog == 3.2 04:21:29 INFO - moznetwork == 0.27 04:21:29 INFO - mozprocess == 0.23 04:21:29 INFO - mozprofile == 0.28 04:21:29 INFO - mozrunner == 6.12 04:21:29 INFO - mozscreenshot == 0.1 04:21:29 INFO - mozsystemmonitor == 0.3 04:21:29 INFO - moztest == 0.7 04:21:29 INFO - mozversion == 1.4 04:21:29 INFO - psutil == 3.1.1 04:21:29 INFO - requests == 1.2.3 04:21:29 INFO - wsgiref == 0.1.2 04:21:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:29 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')]} 04:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 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')]} 04:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:29 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 04:21:29 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 04:21:29 INFO - Using env: {'DISPLAY': ':0', 04:21:29 INFO - 'HOME': '/home/cltbld', 04:21:29 INFO - 'LANG': 'en_US.UTF-8', 04:21:29 INFO - 'LANGUAGE': 'en_US:en', 04:21:29 INFO - 'LOGNAME': 'cltbld', 04:21:29 INFO - 'MAIL': '/var/mail/cltbld', 04:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:29 INFO - 'MOZ_NO_REMOTE': '1', 04:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:29 INFO - 'NO_EM_RESTART': '1', 04:21:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:29 INFO - 'PWD': '/builds/slave/test', 04:21:29 INFO - 'SHELL': '/bin/bash', 04:21:29 INFO - 'SHLVL': '1', 04:21:29 INFO - 'TERM': 'linux', 04:21:29 INFO - 'TMOUT': '86400', 04:21:29 INFO - 'USER': 'cltbld', 04:21:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:29 INFO - '_': '/tools/buildbot/bin/python'} 04:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 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)) 04:21:29 INFO - Cleaning up... 04:21:29 INFO - Return code: 0 04:21:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:21:29 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')]} 04:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 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')]} 04:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 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 0x189c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, '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': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', '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 04:21:29 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 04:21:29 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 04:21:29 INFO - Using env: {'DISPLAY': ':0', 04:21:29 INFO - 'HOME': '/home/cltbld', 04:21:29 INFO - 'LANG': 'en_US.UTF-8', 04:21:29 INFO - 'LANGUAGE': 'en_US:en', 04:21:29 INFO - 'LOGNAME': 'cltbld', 04:21:29 INFO - 'MAIL': '/var/mail/cltbld', 04:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:29 INFO - 'MOZ_NO_REMOTE': '1', 04:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:29 INFO - 'NO_EM_RESTART': '1', 04:21:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:29 INFO - 'PWD': '/builds/slave/test', 04:21:29 INFO - 'SHELL': '/bin/bash', 04:21:29 INFO - 'SHLVL': '1', 04:21:29 INFO - 'TERM': 'linux', 04:21:29 INFO - 'TMOUT': '86400', 04:21:29 INFO - 'USER': 'cltbld', 04:21:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:29 INFO - '_': '/tools/buildbot/bin/python'} 04:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:21:30 INFO - Cleaning up... 04:21:30 INFO - Return code: 0 04:21:30 INFO - Running post-action listener: _resource_record_post_action 04:21:30 INFO - Running post-action listener: _start_resource_monitoring 04:21:30 INFO - Starting resource monitoring. 04:21:30 INFO - ##### 04:21:30 INFO - ##### Finished create-virtualenv step (success) 04:21:30 INFO - ##### 04:21:30 INFO - ##### 04:21:30 INFO - ##### Running install step. 04:21:30 INFO - ##### 04:21:30 INFO - Running pre-action listener: _resource_record_pre_action 04:21:30 INFO - Running main action method: install 04:21:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:30 INFO - Reading from file tmpfile_stdout 04:21:30 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:30 INFO - Reading from file tmpfile_stdout 04:21:30 INFO - Output received: 04:21:30 INFO - Usage: mozinstall [options] installer 04:21:30 INFO - Options: 04:21:30 INFO - -h, --help show this help message and exit 04:21:30 INFO - -d DEST, --destination=DEST 04:21:30 INFO - Directory to install application into. [default: 04:21:30 INFO - "/builds/slave/test"] 04:21:30 INFO - --app=APP Application being installed. [default: firefox] 04:21:30 INFO - mkdir: /builds/slave/test/build/application 04:21:30 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'] 04:21:30 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 04:21:47 INFO - Reading from file tmpfile_stdout 04:21:47 INFO - Output received: 04:21:47 INFO - /builds/slave/test/build/application/firefox/firefox 04:21:47 INFO - Running post-action listener: _resource_record_post_action 04:21:47 INFO - ##### 04:21:47 INFO - ##### Finished install step (success) 04:21:47 INFO - ##### 04:21:47 INFO - ##### 04:21:47 INFO - ##### Running run-tests step. 04:21:47 INFO - ##### 04:21:47 INFO - Running pre-action listener: _resource_record_pre_action 04:21:47 INFO - Running main action method: run_tests 04:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:21:47 INFO - Python 2.7.3 04:21:47 INFO - Return code: 0 04:21:47 INFO - grabbing minidump binary from tooltool 04:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:21:47 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 0x1b5b3b0>, '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 0x1b5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b5c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:21:47 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 04:21:47 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 04:21:47 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 04:21:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:21:47 INFO - Return code: 0 04:21:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:21:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:21:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:21:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:21:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 04:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 04:21:47 INFO - Using env: {'DISPLAY': ':0', 04:21:47 INFO - 'HOME': '/home/cltbld', 04:21:47 INFO - 'LANG': 'en_US.UTF-8', 04:21:47 INFO - 'LANGUAGE': 'en_US:en', 04:21:47 INFO - 'LOGNAME': 'cltbld', 04:21:47 INFO - 'MAIL': '/var/mail/cltbld', 04:21:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:21:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:47 INFO - 'MOZ_NO_REMOTE': '1', 04:21:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:47 INFO - 'NO_EM_RESTART': '1', 04:21:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:47 INFO - 'PWD': '/builds/slave/test', 04:21:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:21:47 INFO - 'SHELL': '/bin/bash', 04:21:47 INFO - 'SHLVL': '1', 04:21:47 INFO - 'TERM': 'linux', 04:21:47 INFO - 'TMOUT': '86400', 04:21:47 INFO - 'USER': 'cltbld', 04:21:47 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231', 04:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:47 INFO - '_': '/tools/buildbot/bin/python'} 04:21:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 04:21:47 INFO - mozversion application_buildid: 20160718013034 04:21:47 INFO - mozversion application_changeset: c8ba6a6eec22033228aad26f08db5ac4ff129461 04:21:47 INFO - mozversion application_display_name: Nightly 04:21:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:21:47 INFO - mozversion application_name: Firefox 04:21:47 INFO - mozversion application_remotingname: firefox 04:21:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:21:47 INFO - mozversion application_vendor: Mozilla 04:21:47 INFO - mozversion application_version: 50.0a1 04:21:47 INFO - mozversion platform_buildid: 20160718013034 04:21:47 INFO - mozversion platform_changeset: c8ba6a6eec22033228aad26f08db5ac4ff129461 04:21:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:21:47 INFO - mozversion platform_version: 50.0a1 04:21:47 INFO - using testdate: 1468840907 04:21:47 INFO - actual date: 1468840907 04:21:47 INFO - starting webserver on 'localhost:44809' 04:21:47 INFO - SUITE-START | Running 2 tests 04:21:47 INFO - TEST-START | dromaeo_css 04:21:47 INFO - operating with platform_type : linux_ 04:21:47 INFO - Initialising browser for dromaeo_css test... 04:21:47 INFO - TEST-INFO | started process 25382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ_Vd76/profile http://localhost:44809/getInfo.html) 04:21:51 INFO - PROCESS | 25382 | JavaScript error: , line 0: uncaught exception: undefined 04:21:52 INFO - PROCESS | 25382 | __metrics Screen width/height:1600/1200 04:21:52 INFO - PROCESS | 25382 | colorDepth:24 04:21:52 INFO - PROCESS | 25382 | Browser inner width/height: 1024/697 04:21:52 INFO - PROCESS | 25382 | __metrics 04:21:52 INFO - PROCESS | 25382 | 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" 04:21:52 INFO - PROCESS | 25382 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:21:53 INFO - TEST-INFO | 25382: exit 0 04:21:53 INFO - Browser initialized. 04:21:53 INFO - Running cycle 1/1 for dromaeo_css test... 04:21:53 INFO - TEST-INFO | started process 25465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ_Vd76/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:22:05 INFO - PROCESS | 25465 | RSS: Main: 158388224 04:22:05 INFO - PROCESS | 25465 | 04:22:57 INFO - PROCESS | 25465 | 1468840977345 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] 04:25:05 INFO - PROCESS | 25465 | Cycle 1(1): loaded http://localhost:44809/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44809/tests/dromaeo/cssquery-ext.html) 04:25:06 INFO - PROCESS | 25465 | RSS: Main: 258310144 04:25:06 INFO - PROCESS | 25465 | 04:28:08 INFO - PROCESS | 25465 | Cycle 1(1): loaded http://localhost:44809/tests/dromaeo/cssquery-ext.html (next: http://localhost:44809/tests/dromaeo/cssquery-jquery.html) 04:28:09 INFO - PROCESS | 25465 | RSS: Main: 238481408 04:28:09 INFO - PROCESS | 25465 | 04:31:11 INFO - PROCESS | 25465 | Cycle 1(1): loaded http://localhost:44809/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44809/tests/dromaeo/cssquery-mootools.html) 04:31:11 INFO - PROCESS | 25465 | RSS: Main: 235552768 04:31:11 INFO - PROCESS | 25465 | 04:34:13 INFO - PROCESS | 25465 | Cycle 1(1): loaded http://localhost:44809/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44809/tests/dromaeo/cssquery-prototype.html) 04:34:14 INFO - PROCESS | 25465 | RSS: Main: 238682112 04:34:14 INFO - PROCESS | 25465 | 04:37:17 INFO - PROCESS | 25465 | Cycle 1(1): loaded http://localhost:44809/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44809/tests/dromaeo/cssquery-yui.html) 04:37:17 INFO - PROCESS | 25465 | RSS: Main: 213385216 04:37:17 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | Cycle 1(1): loaded http://localhost:44809/tests/dromaeo/cssquery-yui.html (next: http://localhost:44809/tests/dromaeo/cssquery-dojo.html) 04:40:20 INFO - PROCESS | 25465 | RSS: Main: 273416192 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | __start_tp_report 04:40:20 INFO - PROCESS | 25465 | _x_x_mozilla_page_load 04:40:20 INFO - PROCESS | 25465 | _x_x_mozilla_page_load_details 04:40:20 INFO - PROCESS | 25465 | |i|pagename|runs| 04:40:20 INFO - PROCESS | 25465 | |0;dojo.html;3072.554479600459;3073.969260307397;3091.845407729613;3111.9533207001887;3135.905922822316;5279.947200527993;5339.092354299769;5361.51746342829;5389.676619402839;5469.097598896187;5281.947180528194;5285.656432331895;5301.443348448415;5314.867128321794;5329.786808527654;4741.620670346381;4826.46908840029;4832.71003739775;4845.466998630165;4964.230544265626;4883.706977581356;4893.926591101136;4930.186519224341;4931.408231012284;4948.183549714293;4739.454962679284;4746.90506189876;4753.643476739258;4847.75761211938;4911.895920045856;4845.345878306419;4855.6115510759055;4863.367762190928;4914.483979182206;4969.453360130383;4902.068606964701;4902.117618828609;4904.386951631046;4907.2393778964315;4912.484189160123;5238.266642670029;5247.10799164143;5308.787648494055;5369.516743493065;5371.167469042305;5672.943270567324;5711.62874413166;5726.455986681259;5739.684317362543;5742;5455.0453670607485;5717.485426311652;5770.913436298459;5775.162601422812;5776.277965254343;5234.450382709837;5246.973765131188;5257.658252213594;5344.465553444663;5347.946520534822;5824.621399609012;5827.446392592697;5832.970835145882;5838.095095260242;5843.152742852262;5418.945810541923;5550.777968881281;5607;5619.213310136584;5625.043742563774;5629.2681951346885;5769.1634712967225;5771.192033115367;5775.913361299584;5779.3642699303045;5686.34607020198;5692.430756924275;5693.886122277531;5694.117411801178;5707.514861236758;5493.862653433616;5537.58468114893;5557.888842223133;5696.51579615729;5705.6291341063525;4281.785910704452;4289.571042895748;4297.763623000703;4324.416203812445;4336.609705126553;3822.10180607557;3849.403342481864;3865.111024464333;3880.1269714314617;3896.493455850778;5313.1764576490705;5319.468053194727;5352.277442545284;5383.67697938125;5438.483344082306;5761.654300741969;5779.711014449261;5783.479486846204;5787.045137552257;5822.737976791054;5474.42518535556;5477.835664930059;5491.505764481136;5491.560675145979;5494.340679118532;4788.425388953278;4898.657094003457;4971.080350135164;4971.080350135237;5001.324821149171;5896.056630939115;5903.468687818117;5905.291365036137;5908.20239267702;5942;5569.470900264469;5766.221560089334;5778.56660750437;5784.681842498703;5785.161151633074;5894.557908156819;5913.70431478433;5916.3492015877355;5952.255968003999;5970.641761494324;5596.328440586993;5597.720113994365;5597.748101335368;5602.383737788921;5647.632903861236;5445.101558242845;5614.494695477427;5623.915641265467;5676.744546495335;5682.374938756816;5527;5539.778408863762;5547.140193270049;5583.69289689071;5651.547876169814;5591.077472217039;5594.41258667834;5597.468240517144;5607.943920560905;5608.242887210175;5447.754851031634;5450.236966824568;5456.7544460498575;5498.862528436821;5613;5576.553875690016;5622.915656265078;5628.268325117709;5630.718464076862;5637.3235211774845;5428.402875683592;5437.993971115198;5441.646292990943;5449.972750136224;5450.890982180414 04:40:20 INFO - PROCESS | 25465 | |1;ext.html;591.5563327504372;602.1990752299441;626.7242413338133;632.2286810091689;633.83837121534;32985.50521742173;36066.81966590167;36121.277574448526;42116.57883421165;43503.56496435035;44922.55077449224;45512.317315240216;45562.544374556244;45566.772166139126;45753.542464575345;8923.91076089239;9053.094690530927;9058.320625953047;9073.863892041625;9076.500792456412;8867.068957759438;8943.95528022359;8950.41822281553;8952.149545793172;8955.596998135066;8964.282857371394;8985.326100542452;9001.549922503882;9005;9007.909920900773;8883.644654213824;8924.509151996637;8924.732258032205;8969.416987895798;8971.551422428853;8949.463032218087;9008;9016.729498115066;9018.143276388799;9018.549072546411;42414.36378454295;43252.567474325166;43274.350884736756;43275;43278.35082473765;3491.793240437907;3572.910677233064;3615.7649752765988;3634.0914771307175;3745.101175717808;32410.675893241;32740.17456383918;32899.50650740241;33371.66628333734;34144.48783268253;4376.9343459847805;4420.359047938063;4433.467983841927;4451.688381813275;4524.954750452486;352092;352317.23841380887;352590;355196;355551;60627.69686151541;60723.392766071775;60809.391906081255;60813;60864.69567652133;54202;60481.69759151176;61824.690876545326;62158.68920655458;67700.6614966922;15349.6162595936;15401.845981540266;15448.150351730537;15452.768208476882;15455.30451129702;11127.332360058423;11445.48495317696;11451.141164412702;11488.885111148782;11495.597654082067;17451.38920137789;18000.9099954502;18015.369462069026;18123.637527249382;18572.349967751063;6405.420124610926;6411.551191416556;6457.644829534421;6597.703103360361;6631;990.9752256193509;1062.219268837404;1163.2555164694602;1167.5563285951455;1186.602488166474;33041.834790825895;33062;33141.83429082839;33232.833835830665;33650.158746031055;1203.3201241298639;1205.73473835756;1210.8244304575787;1211.6062279759115;1236.7341021680381;17253.482395528154;17538.473845784647;17668.646627067643;17713.557161071076;17795.454204785594;149843.25078374756;150631;151143;152106;153130;59668;59774;59790.50523736941;59825.401745982854;59906;1246.3954981833804;1338.2639548248424;1389.3817251323064;1389.7567925612977;1395.8045873577707;32650.183745405604;34047;34360.48459273063;34378.82810585931;34603.13492162716;50616;51078;51603;51608.48391516037;51662.225066624786;374577;377272.9318008;378127;378825;379441;27599.448011039265;28700.282492937844;28725.138245852657;30021;30026.54960175643;49272;49646.25530616971;49965;51106;51359;41297.380539291335;42104.57895421129;42888;43686.56313436825;43835.12329753451;41682.79158604187;42368.364474533526;42613.36079958865;43215.78392108019;43367.34948975705;41742.58257417508;42418.5758142427;42909;43357;43387.132257355304;36439;37408.251834963696;38037.80981095077;38140.809295953346;38163.42754858735;28642.57036144501;28678.426431470838;28721.42557148887;28731.5690264642;28758 04:40:20 INFO - PROCESS | 25465 | |2;jquery.html;380.7372912690242;399.4308110941907;404.45600667102747;406.7864371205117;425.291889004807;12002.51989920402;14300;19022;20270.29053983111;20271;21109.788902110973;21749.238776642822;22406.439839004033;22495.887520562414;22714.88642556785;6472;6594.043863639781;6741.426978706816;6815.522913396064;6816.965915170417;6451.2903580606335;6700.028495868096;6708.664566771642;6720.1599800025;6762.120924279837;6718.59688418694;6742.157230346207;6749.0551322814845;6753.45972322213;6758.290379510155;6695.631740254284;6698.564593301444;7394.889076663854;7762;7769.728059517946;7314.414846812242;7516.361109305715;7556.282153195492;7563.886541701879;7611.619419029026;25833.612495812577;26778.196654100408;27335;27427.31431714203;27718;2442.8629403438595;2509.3099397665605;2548.706898706654;2582.5093232285994;3263.184203949013;15275;15691.1369874658;16347.182640868028;16396.426125085563;17102.401415950506;2085.937421877346;2111.3771437425844;2176.9891150544145;2177.912883484659;2227.454273702937;95029.52485237598;95279.52360238224;97182;98324.50837745838;102650.48674756654;16989.660206795794;17773.289068437116;17826.821731782777;17977.820221797876;18017.2793088275;32348;32509.51235731466;32562.67437325644;33111;33125.17187070294;9793.51032448389;10007.749806254902;10072.798544029078;10137.341072830242;10267.383956962607;8124.634391452399;8150.38872084596;8233.79415514617;8284.668613255522;8447.493150410872;8872.24585910205;9028.954855225682;9145.131212534798;9145.176934075966;9221.123993220634;3263.3636440894275;3573.2674801666117;3591.2817436512278;3667.94498082529;3705.8118633912736;906.5467266366817;947.218544700611;968.5786682793008;977.2426369563645;990.9108180263667;19056.71414928777;20549.48626284325;20628.69056964147;20701.89649051745;20732.170713171305;983.2085171436919;988.5106872098214;988.8467287570438;1021.6015753856002;1059.3485006720966;10059.497025148714;11040.282381645167;11089.5564177432;11095.167862410352;11165.665030049111;453346;461117.1664849993;467366;473720;475360;97926;98472;98830.5058474703;99462;101488.49255753822;978.3933960944261;992.8361820299714;1014.4065721552827;1016.7102375822855;1017.0338178730236;17638.911805441403;18019;18136.274549017893;18231.817681823544;18518.0740962954;42195.36706949337;44115.33826992662;44845.775771120934;45572.77213613911;46039.07921841477;53381.733091334296;62135.68932155491;62573.37426625675;63865.68067159635;64056.67971660112;8277.420580559303;8654.22112009922;8669.176428239307;8677;8767.912320876709;7373.741919033023;10943.85089565616;12061.216020958609;12646;13589.32053397346;11528.827067593826;11638.53445862156;11654.184207105422;11886.227395219283;12088.818667720167;11915.40422978865;11982.400879955792;11988;12065.939670301592;12318.322492263012;12010.399480026139;12061.15571909993;12114;12385.380730963596;12489.750204996031;7714.3442807362;7758.728444504415;8674.392792504465;8839.381243312906;8957.059508751749;11350.829737554108;11538.653840384466;11818.349990750592;11871.228370155912;12275.631731048083 04:40:20 INFO - PROCESS | 25465 | |3;mootools.html;1343.1471015904897;1457.577302582251;1521.9954829812323;1642;1752.8772985890994;8417.957910210447;8807.603657835396;8844.823103537925;8976.910230897689;9063.184313411792;8846.380753347268;9025;9027.052159523255;9027.142421469965;9029.548522573878;2399.424138206831;2424.7575242475787;2425.514897020594;2426.417659761662;2428.429319110008;2432.19737486629;2448.24104527597;2472.777450029497;2477.0339567568662;2479.913203037894;2331.9533609327805;2336.19401306549;2360.5396947595227;2367.254314890804;2369.514249578841;2309.457277539777;2337.8947947342413;2339.9298021059312;2363.5863723848342;2363.6927199464044;2272.1706577099403;2341.461463863304;2342.8828558572004;2348.0255693887016;2348.3072493614427;7817.882731758971;8547.957260213721;8971.102889710977;8999.415038022573;9056.728298151065;5289.497497737709;5404.189371594253;5414.945850541483;5423.267858839045;5424.755885985101;3529.6117427083;3579.9642003579893;3619.6380361963857;3640.7451478396524;3644.5808731995894;1179.2099293473393;1186.6499382682043;1195.396324855951;1214.4413569757921;1223.4739062203248;12478.251304921643;12574.182678125984;12575.05687073464;12599.622011339674;12611.558595449114;4493.191225579427;4528.977355113269;4656.3946686930485;4659.5573420525;4737.620990320766;10393.740156496147;10464.947675261727;10471.895281047244;10473.371597704163;10475.84286235707;5392.865178370572;5424.7016414096815;5424.810131645373;5427.3215848019;5430.429804870431;4820.30105634681;4983.775730092155;5000.974995125074;5001.549860512571;5021.121303771825;3789.1853251551047;3848.3265428549894;3851.114243723959;3880.1269714314617;3942.842286308573;1126.284809146198;1133.4502766158407;1142.948567314473;1146.7706458708292;1167.2646232873235;1024.2318261304022;1024.2420608749471;1026.8408396698524;1028.6502589119586;1033.9017793309624;6076.787312444132;6160.476359509402;6214.5255416100335;6287.402696743802;6364.49084073273;469.840254313535;471.0555336550195;477.3388856433864;484.79880849447085;497.54226111977067;4234.894127646772;4414.580614841585;4425.3140763181755;4435.822567097344;4491.932621010687;10908.727281818017;11241.988221060144;11257.099432045417;11316.943415283036;11317.886821131848;11231.045361144232;11232.382218978035;11244.763076061627;11246.66260012201;11248.606298779667;7760.185180556074;8051.758447246709;8065.153158918347;8423.536705481258;8500.829983400297;6224.595401298901;6259.186305780219;6356.687452881551;6413.967930160319;6470.708818103102;10311.793764124826;10381.169506439315;10383.494393312776;10492.160627149962;10493.265471417233;10455.47722613851;10687.679369618923;10781.730456738644;10809.243352965532;10812.405317707602;7539.283768042027;7618.8095297618;7633.618319084135;7645.541267523967;7694.422918281263;8153.918460815553;8164.550949697824;8184.754457366289;8216.712415065444;8217.712380066669;7426.405887529093;7544.962275188589;7559.092908850973;7608.733694320672;7672.424568157522;7214.314640109181;7401.444891633041;7442.404607631268;7542.208068152765;7586.734464293723;7051.294870512907;7080.079589653312;7097.893531596927;7099.325564071405;7108.182559005781;5085.135526960352;5271.341082364705;5358.115910874662;5374.435684253253;5410.242566040679;5383.650062745909;5446.373667028342;5452.863678407913;5520.254765606592;5643.858903527444 04:40:20 INFO - PROCESS | 25465 | |4;prototype.html;328.6729703944574;330.9106541233867;340.4552715654951;340.798928632107;342.2316898562726;5293.861819708762;5311.282976798136;5335.306410166682;5353.7953960359;5366.4633536646315;4993.575546078579;5095.566876815466;5251.159814429692;5284.577233821295;5288.867778305545;4129.442525259096;4160.355144952538;4183.205191013698;4187.497500299968;4201.705880588368;4163.646090082347;4166.729162604436;4167.312393455076;4172.290710579209;4187.1416359646355;3892.5523564789987;3948.7038472114564;4029.6171863672903;4119.217348703752;4159;4136.772477513736;4150.564190759972;4157.896052598679;4161.167766446722;4162.708610397258;3694.2796154749835;3697.3159965406485;3706.4803430593597;3710.591834898159;3723.83242754074;5112.590992720573;5125.6155788315655;5148.065602606478;5153.304303918998;5214.661047031931;4713.5993440557595;4718.834840780556;4722.929156062662;4739.526047395268;4742.739149346785;5536.4186760390385;5569.162176481751;5601.123623586103;5603.10350343943;5603.775848966036;3907.7459965102175;4032.475778148851;4082.9183416331507;4087.7925920003163;4087.9591204087874;5747.770089196469;5782.9012487627215;5791.449812267828;5796.333421656522;5800.738966746507;4194.6853985950565;4195.056112374752;4201.45381100455;4204.642605378516;4205.474315710536;3961.9405708914387;4170.332746760504;4188.958110418917;4236.237477254123;4258;4730.763461826964;4738.3129446230105;4770.0936822003705;4771.093492236464;4780.904381912412;5548.778048878;5564.777408903679;5590.329160500766;5593.804216852391;5596.265157801095;1352.5468967895865;1355.3155656393656;1422.8506006869336;1429.121090529317;1437.015644283669;949.1884438804723;982.9705108846746;993.6571882700457;995.9352642078242;1000.8048430555972;5212.55693266069;5330.734437863893;5345.305110335631;5407.513323800877;5407.810726624549;5620.269364982526;5624.3813180550105;5637.154426836008;5645.23789288449;5656.359655699884;5260.395054568697;5297;5303.549198318109;5320.627556071116;5330.120530112488;2891.79757416983;2947.985260073686;2951.9262018449713;3018.351054523288;3028.772842036856;5881.558883083786;5918.691710232926;5920.2895652521975;5929.466348028698;5935.52515798735;5631.324241091095;5858.8242352729485;5859.296884373903;5895.616784908967;5916.319623243296;5864.912026319608;5887.8233652990475;5899.584099816099;5916.437938395846;5925.874083924041;5690.231818704422;5726.255586773693;5733.799317023884;5770.855728606818;5793.91309130355;5384.380796208487;5643.1535269709875;5674.148877668383;5690.260266165372;5706.172604972173;5915.792947246826;5940.584159108821;5950.553708471795;5973.313068997121;5975.237304995124;4093.5087789465456;4117.444145040501;4148.108156746193;4150.667946564328;4157.08544120293;5660.575456840836;5727.455891690282;5754.827355179351;5755.510781583613;5776.162456443708;5360.43715409892;5598.552115830642;5601.6639001658405;5610.4670056344585;5613.522850557748;5462.399136094947;5493.176023596333;5497.890042199054;5503.174523821459;5586.972065139648;5584.766093562607;5628.268325117709;5649;5661.631993920382;5674.2623458950075;5539.14143307788;5562.332520097614;5653.660780353192;5681.318241811009;5692.971535142297;5500.80747173847;5535.197396377584;5554.305711786027;5556.194351819045;5574.163875418558 04:40:20 INFO - PROCESS | 25465 | |5;yui.html;786.6617354537553;806.3831169155598;817.5830326533467;824.4270232188629;861.7931696392864;15016.549503514898;16611.750823737653;16644.08457534835;16709.582260443494;16745.07902065386;15015.624609384771;15897.284622192;15926.442574509894;16361.590960226;16367.4271400501;678.6403206300669;814.4298990706515;827.2843989948711;831.6132998155856;832.5587438657491;694.482610455209;708.472188219777;708.7058870568708;810.6149578949982;829.6432534010373;868.2663149638545;871.1767379826067;871.3203701113142;871.376965469689;872.5419154943673;863.9395242333039;864.0840708848641;868.2012548455436;869.3827382558391;869.5000374784524;514.2414937966507;621.3382747374077;634.6890023888308;637.0380725105078;639.9072134540465;15300.234988250542;15367.538973830691;15392.230388480531;15393.384264629402;15427.768583471257;13455.461781528727;13498.325083745773;14328.283585820667;14343.282835858165;14371.92814035923;12993.935030324883;13019.47922083125;13103.86896131036;13317.533886313933;13385.86614133856;3848.730588858781;3919.6074450754923;4013.097053163044;4013.1572369802375;4045.1707399982934;78043.6097819513;78312.21687783163;79702.60148699277;79779;79822.60088699519;11479.942600286946;11492.310461372344;11765.411729413496;11794.410279485992;11897.762044759229;22180.556388872134;22406.215782447536;22615.660765088538;22682;22758.431039223822;8883.629503829778;8898.822023559493;8904.19862212404;9059.094090590888;9105.81788364229;4662.7202367858035;4719.339292499053;4772.6420518461255;4874.683145595525;4940.110780059624;3466.9479957800654;3621.4748861415474;3629.510016147839;3660.3228402745576;3661.1579336752698;574.0642752313786;577.4485366475091;593.1429084972174;593.8545056461195;605.5428151745404;741.9554826710414;766.8044648614567;767.9347255483347;784.4234487651571;788.932940700047;9925.255605829592;10110.949445252874;10121.291509594404;10279.588816447407;10350.896491034993;634.3475246981639;735.5386257041446;759.0966749567997;773.1225059557443;783.8863364812071;4765.571098601143;4929.605631549467;4999.950000500021;5090.567301779316;5100;112347.4382628098;114880;115034;115976.24261331247;117148;64933.67533162304;70601.64699176472;85305;93695.53152234331;95319.52340238393;78302;84807.5759621198;84919.72620410699;84959.5752021236;85683;10464.686059418229;10578.626936593637;10749.355038697704;10806.189535785004;10842.295250808673;49669.75165124296;49686.75156624339;50299;50976.23535646894;51943.74028129835;45044;50015.749921250164;50582.74708626433;62318.376816231255;74845.6257718708;13100.60698179056;13396.732065358834;13460.461581536629;13580.93209533946;13717.794233086712;15134.772978405112;15588.610284742972;15606.84393156054;15906.363745450515;16182.676346472768;10778.407277369957;14425.134491930517;14571.635709107357;14604.488842890449;14642.121472711671;13873.722525549634;14112.858871411154;14364.947584942216;14399.178841209115;15187.392504299703;13947.58157255284;14252.786208207093;14272.14367137975;14610.488632897797;14647.26763661834;10980.615678451215;11555.942220288845;11732.354720490455;12226.266424014588;12418.627441176779;11762.411879406167;12139;12183.208091474025;12526.622071571937;12652.936735316265 04:40:20 INFO - PROCESS | 25465 | __end_tp_report 04:40:20 INFO - PROCESS | 25465 | __start_cc_report 04:40:20 INFO - PROCESS | 25465 | _x_x_mozilla_cycle_collect,888 04:40:20 INFO - PROCESS | 25465 | __end_cc_report 04:40:20 INFO - PROCESS | 25465 | __startTimestamp1468842020595__endTimestamp 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | ------- Summary: start ------- 04:40:20 INFO - PROCESS | 25465 | Number of tests: 6 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | [#0] dojo.html Cycles:165 Average:5297.46 Median:5477.84 stddev:603.55 (11.0%) stddev-sans-first:579.61 04:40:20 INFO - PROCESS | 25465 | Values: 3072.6 3074.0 3091.8 3112.0 3135.9 5279.9 5339.1 5361.5 5389.7 5469.1 5281.9 5285.7 5301.4 5314.9 5329.8 4741.6 4826.5 4832.7 4845.5 4964.2 4883.7 4893.9 4930.2 4931.4 4948.2 4739.5 4746.9 4753.6 4847.8 4911.9 4845.3 4855.6 4863.4 4914.5 4969.5 4902.1 4902.1 4904.4 4907.2 4912.5 5238.3 5247.1 5308.8 5369.5 5371.2 5672.9 5711.6 5726.5 5739.7 5742.0 5455.0 5717.5 5770.9 5775.2 5776.3 5234.5 5247.0 5257.7 5344.5 5347.9 5824.6 5827.4 5833.0 5838.1 5843.2 5418.9 5550.8 5607.0 5619.2 5625.0 5629.3 5769.2 5771.2 5775.9 5779.4 5686.3 5692.4 5693.9 5694.1 5707.5 5493.9 5537.6 5557.9 5696.5 5705.6 4281.8 4289.6 4297.8 4324.4 4336.6 3822.1 3849.4 3865.1 3880.1 3896.5 5313.2 5319.5 5352.3 5383.7 5438.5 5761.7 5779.7 5783.5 5787.0 5822.7 5474.4 5477.8 5491.5 5491.6 5494.3 4788.4 4898.7 4971.1 4971.1 5001.3 5896.1 5903.5 5905.3 5908.2 5942.0 5569.5 5766.2 5778.6 5784.7 5785.2 5894.6 5913.7 5916.3 5952.3 5970.6 5596.3 5597.7 5597.7 5602.4 5647.6 5445.1 5614.5 5623.9 5676.7 5682.4 5527.0 5539.8 5547.1 5583.7 5651.5 5591.1 5594.4 5597.5 5607.9 5608.2 5447.8 5450.2 5456.8 5498.9 5613.0 5576.6 5622.9 5628.3 5630.7 5637.3 5428.4 5438.0 5441.6 5450.0 5450.9 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | [#1] ext.html Cycles:180 Average:48410.80 Median:42110.58 stddev:82076.63 (194.9%) stddev-sans-first:82228.35 04:40:20 INFO - PROCESS | 25465 | Values: 591.6 602.2 626.7 632.2 633.8 32985.5 36066.8 36121.3 42116.6 43503.6 44922.6 45512.3 45562.5 45566.8 45753.5 8923.9 9053.1 9058.3 9073.9 9076.5 8867.1 8944.0 8950.4 8952.1 8955.6 8964.3 8985.3 9001.5 9005.0 9007.9 8883.6 8924.5 8924.7 8969.4 8971.6 8949.5 9008.0 9016.7 9018.1 9018.5 42414.4 43252.6 43274.4 43275.0 43278.4 3491.8 3572.9 3615.8 3634.1 3745.1 32410.7 32740.2 32899.5 33371.7 34144.5 4376.9 4420.4 4433.5 4451.7 4525.0 352092.0 352317.2 352590.0 355196.0 355551.0 60627.7 60723.4 60809.4 60813.0 60864.7 54202.0 60481.7 61824.7 62158.7 67700.7 15349.6 15401.8 15448.2 15452.8 15455.3 11127.3 11445.5 11451.1 11488.9 11495.6 17451.4 18000.9 18015.4 18123.6 18572.3 6405.4 6411.6 6457.6 6597.7 6631.0 991.0 1062.2 1163.3 1167.6 1186.6 33041.8 33062.0 33141.8 33232.8 33650.2 1203.3 1205.7 1210.8 1211.6 1236.7 17253.5 17538.5 17668.6 17713.6 17795.5 149843.3 150631.0 151143.0 152106.0 153130.0 59668.0 59774.0 59790.5 59825.4 59906.0 1246.4 1338.3 1389.4 1389.8 1395.8 32650.2 34047.0 34360.5 34378.8 34603.1 50616.0 51078.0 51603.0 51608.5 51662.2 374577.0 377272.9 378127.0 378825.0 379441.0 27599.4 28700.3 28725.1 30021.0 30026.5 49272.0 49646.3 49965.0 51106.0 51359.0 41297.4 42104.6 42888.0 43686.6 43835.1 41682.8 42368.4 42613.4 43215.8 43367.3 41742.6 42418.6 42909.0 43357.0 43387.1 36439.0 37408.3 38037.8 38140.8 38163.4 28642.6 28678.4 28721.4 28731.6 28758.0 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | [#2] jquery.html Cycles:180 Average:30589.93 Median:18349.22 stddev:77362.31 (421.6%) stddev-sans-first:77546.08 04:40:20 INFO - PROCESS | 25465 | Values: 380.7 399.4 404.5 406.8 425.3 12002.5 14300.0 19022.0 20270.3 20271.0 21109.8 21749.2 22406.4 22495.9 22714.9 6472.0 6594.0 6741.4 6815.5 6817.0 6451.3 6700.0 6708.7 6720.2 6762.1 6718.6 6742.2 6749.1 6753.5 6758.3 6695.6 6698.6 7394.9 7762.0 7769.7 7314.4 7516.4 7556.3 7563.9 7611.6 25833.6 26778.2 27335.0 27427.3 27718.0 2442.9 2509.3 2548.7 2582.5 3263.2 15275.0 15691.1 16347.2 16396.4 17102.4 2085.9 2111.4 2177.0 2177.9 2227.5 95029.5 95279.5 97182.0 98324.5 102650.5 16989.7 17773.3 17826.8 17977.8 18017.3 32348.0 32509.5 32562.7 33111.0 33125.2 9793.5 10007.7 10072.8 10137.3 10267.4 8124.6 8150.4 8233.8 8284.7 8447.5 8872.2 9029.0 9145.1 9145.2 9221.1 3263.4 3573.3 3591.3 3667.9 3705.8 906.5 947.2 968.6 977.2 990.9 19056.7 20549.5 20628.7 20701.9 20732.2 983.2 988.5 988.8 1021.6 1059.3 10059.5 11040.3 11089.6 11095.2 11165.7 453346.0 461117.2 467366.0 473720.0 475360.0 97926.0 98472.0 98830.5 99462.0 101488.5 978.4 992.8 1014.4 1016.7 1017.0 17638.9 18019.0 18136.3 18231.8 18518.1 42195.4 44115.3 44845.8 45572.8 46039.1 53381.7 62135.7 62573.4 63865.7 64056.7 8277.4 8654.2 8669.2 8677.0 8767.9 7373.7 10943.9 12061.2 12646.0 13589.3 11528.8 11638.5 11654.2 11886.2 12088.8 11915.4 11982.4 11988.0 12065.9 12318.3 12010.4 12061.2 12114.0 12385.4 12489.8 7714.3 7758.7 8674.4 8839.4 8957.1 11350.8 11538.7 11818.3 11871.2 12275.6 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | [#3] mootools.html Cycles:180 Average:5890.68 Median:4511.08 stddev:3366.87 (74.6%) stddev-sans-first:3358.97 04:40:20 INFO - PROCESS | 25465 | Values: 1343.1 1457.6 1522.0 1642.0 1752.9 8418.0 8807.6 8844.8 8976.9 9063.2 8846.4 9025.0 9027.1 9027.1 9029.5 2399.4 2424.8 2425.5 2426.4 2428.4 2432.2 2448.2 2472.8 2477.0 2479.9 2332.0 2336.2 2360.5 2367.3 2369.5 2309.5 2337.9 2339.9 2363.6 2363.7 2272.2 2341.5 2342.9 2348.0 2348.3 7817.9 8548.0 8971.1 8999.4 9056.7 5289.5 5404.2 5414.9 5423.3 5424.8 3529.6 3580.0 3619.6 3640.7 3644.6 1179.2 1186.6 1195.4 1214.4 1223.5 12478.3 12574.2 12575.1 12599.6 12611.6 4493.2 4529.0 4656.4 4659.6 4737.6 10393.7 10464.9 10471.9 10473.4 10475.8 5392.9 5424.7 5424.8 5427.3 5430.4 4820.3 4983.8 5001.0 5001.5 5021.1 3789.2 3848.3 3851.1 3880.1 3942.8 1126.3 1133.5 1142.9 1146.8 1167.3 1024.2 1024.2 1026.8 1028.7 1033.9 6076.8 6160.5 6214.5 6287.4 6364.5 469.8 471.1 477.3 484.8 497.5 4234.9 4414.6 4425.3 4435.8 4491.9 10908.7 11242.0 11257.1 11316.9 11317.9 11231.0 11232.4 11244.8 11246.7 11248.6 7760.2 8051.8 8065.2 8423.5 8500.8 6224.6 6259.2 6356.7 6414.0 6470.7 10311.8 10381.2 10383.5 10492.2 10493.3 10455.5 10687.7 10781.7 10809.2 10812.4 7539.3 7618.8 7633.6 7645.5 7694.4 8153.9 8164.6 8184.8 8216.7 8217.7 7426.4 7545.0 7559.1 7608.7 7672.4 7214.3 7401.4 7442.4 7542.2 7586.7 7051.3 7080.1 7097.9 7099.3 7108.2 5085.1 5271.3 5358.1 5374.4 5410.2 5383.7 5446.4 5452.9 5520.3 5643.9 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | [#4] prototype.html Cycles:180 Average:4705.77 Median:5340.31 stddev:1380.78 (25.9%) stddev-sans-first:1345.01 04:40:20 INFO - PROCESS | 25465 | Values: 328.7 330.9 340.5 340.8 342.2 5293.9 5311.3 5335.3 5353.8 5366.5 4993.6 5095.6 5251.2 5284.6 5288.9 4129.4 4160.4 4183.2 4187.5 4201.7 4163.6 4166.7 4167.3 4172.3 4187.1 3892.6 3948.7 4029.6 4119.2 4159.0 4136.8 4150.6 4157.9 4161.2 4162.7 3694.3 3697.3 3706.5 3710.6 3723.8 5112.6 5125.6 5148.1 5153.3 5214.7 4713.6 4718.8 4722.9 4739.5 4742.7 5536.4 5569.2 5601.1 5603.1 5603.8 3907.7 4032.5 4082.9 4087.8 4088.0 5747.8 5782.9 5791.4 5796.3 5800.7 4194.7 4195.1 4201.5 4204.6 4205.5 3961.9 4170.3 4189.0 4236.2 4258.0 4730.8 4738.3 4770.1 4771.1 4780.9 5548.8 5564.8 5590.3 5593.8 5596.3 1352.5 1355.3 1422.9 1429.1 1437.0 949.2 983.0 993.7 995.9 1000.8 5212.6 5330.7 5345.3 5407.5 5407.8 5620.3 5624.4 5637.2 5645.2 5656.4 5260.4 5297.0 5303.5 5320.6 5330.1 2891.8 2948.0 2951.9 3018.4 3028.8 5881.6 5918.7 5920.3 5929.5 5935.5 5631.3 5858.8 5859.3 5895.6 5916.3 5864.9 5887.8 5899.6 5916.4 5925.9 5690.2 5726.3 5733.8 5770.9 5793.9 5384.4 5643.2 5674.1 5690.3 5706.2 5915.8 5940.6 5950.6 5973.3 5975.2 4093.5 4117.4 4148.1 4150.7 4157.1 5660.6 5727.5 5754.8 5755.5 5776.2 5360.4 5598.6 5601.7 5610.5 5613.5 5462.4 5493.2 5497.9 5503.2 5587.0 5584.8 5628.3 5649.0 5661.6 5674.3 5539.1 5562.3 5653.7 5681.3 5693.0 5500.8 5535.2 5554.3 5556.2 5574.2 04:40:20 INFO - PROCESS | 25465 | 04:40:20 INFO - PROCESS | 25465 | [#5] yui.html Cycles:180 Average:20157.95 Median:3966.35 stddev:28020.11 (706.4%) stddev-sans-first:28060.96 04:40:20 INFO - PROCESS | 25465 | Values: 786.7 806.4 817.6 824.4 861.8 15016.5 16611.8 16644.1 16709.6 16745.1 15015.6 15897.3 15926.4 16361.6 16367.4 678.6 814.4 827.3 831.6 832.6 694.5 708.5 708.7 810.6 829.6 868.3 871.2 871.3 871.4 872.5 863.9 864.1 868.2 869.4 869.5 514.2 621.3 634.7 637.0 639.9 15300.2 15367.5 15392.2 15393.4 15427.8 13455.5 13498.3 14328.3 14343.3 14371.9 12993.9 13019.5 13103.9 13317.5 13385.9 3848.7 3919.6 4013.1 4013.2 4045.2 78043.6 78312.2 79702.6 79779.0 79822.6 11479.9 11492.3 11765.4 11794.4 11897.8 22180.6 22406.2 22615.7 22682.0 22758.4 8883.6 8898.8 8904.2 9059.1 9105.8 4662.7 4719.3 4772.6 4874.7 4940.1 3466.9 3621.5 3629.5 3660.3 3661.2 574.1 577.4 593.1 593.9 605.5 742.0 766.8 767.9 784.4 788.9 9925.3 10110.9 10121.3 10279.6 10350.9 634.3 735.5 759.1 773.1 783.9 4765.6 4929.6 5000.0 5090.6 5100.0 112347.4 114880.0 115034.0 115976.2 117148.0 64933.7 70601.6 85305.0 93695.5 95319.5 78302.0 84807.6 84919.7 84959.6 85683.0 10464.7 10578.6 10749.4 10806.2 10842.3 49669.8 49686.8 50299.0 50976.2 51943.7 45044.0 50015.7 50582.7 62318.4 74845.6 13100.6 13396.7 13460.5 13580.9 13717.8 15134.8 15588.6 15606.8 15906.4 16182.7 10778.4 14425.1 14571.6 14604.5 14642.1 13873.7 14112.9 14364.9 14399.2 15187.4 13947.6 14252.8 14272.1 14610.5 14647.3 10980.6 11555.9 11732.4 12226.3 12418.6 11762.4 12139.0 12183.2 12526.6 12652.9 04:40:20 INFO - PROCESS | 25465 | -------- Summary: end -------- 04:40:20 INFO - PROCESS | 25465 | 04:40:21 INFO - TEST-INFO | 25465: exit 0 04:40:21 INFO - TEST-OK | dromaeo_css | took 1113435ms 04:40:21 INFO - TEST-START | kraken 04:40:21 INFO - operating with platform_type : linux_ 04:40:21 INFO - Initialising browser for kraken test... 04:40:21 INFO - TEST-INFO | started process 25656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLcqTK7/profile http://localhost:44809/getInfo.html) 04:40:24 INFO - PROCESS | 25656 | JavaScript error: , line 0: uncaught exception: undefined 04:40:25 INFO - PROCESS | 25656 | __metrics Screen width/height:1600/1200 04:40:25 INFO - PROCESS | 25656 | colorDepth:24 04:40:25 INFO - PROCESS | 25656 | Browser inner width/height: 1024/697 04:40:25 INFO - PROCESS | 25656 | __metrics 04:40:26 INFO - PROCESS | 25656 | 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" 04:40:26 INFO - PROCESS | 25656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:40:27 INFO - TEST-INFO | 25656: exit 0 04:40:27 INFO - Browser initialized. 04:40:27 INFO - Running cycle 1/1 for kraken test... 04:40:27 INFO - TEST-INFO | started process 25743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLcqTK7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:39 INFO - PROCESS | 25743 | RSS: Main: 158105600 04:40:39 INFO - PROCESS | 25743 | 04:40:41 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44809/tests/kraken/driver.html?testName=audio-beat-detection) 04:40:42 INFO - PROCESS | 25743 | RSS: Main: 217464832 04:40:42 INFO - PROCESS | 25743 | 04:40:45 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44809/tests/kraken/driver.html?testName=audio-dft) 04:40:45 INFO - PROCESS | 25743 | RSS: Main: 201007104 04:40:45 INFO - PROCESS | 25743 | 04:40:49 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44809/tests/kraken/driver.html?testName=audio-fft) 04:40:49 INFO - PROCESS | 25743 | RSS: Main: 270831616 04:40:49 INFO - PROCESS | 25743 | 04:40:51 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44809/tests/kraken/driver.html?testName=audio-oscillator) 04:40:52 INFO - PROCESS | 25743 | RSS: Main: 181538816 04:40:52 INFO - PROCESS | 25743 | 04:40:54 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44809/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:40:55 INFO - PROCESS | 25743 | RSS: Main: 185290752 04:40:55 INFO - PROCESS | 25743 | 04:40:58 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44809/tests/kraken/driver.html?testName=imaging-darkroom) 04:40:58 INFO - PROCESS | 25743 | RSS: Main: 221757440 04:40:58 INFO - PROCESS | 25743 | 04:41:02 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44809/tests/kraken/driver.html?testName=imaging-desaturate) 04:41:02 INFO - PROCESS | 25743 | RSS: Main: 222101504 04:41:02 INFO - PROCESS | 25743 | 04:41:05 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44809/tests/kraken/driver.html?testName=json-parse-financial) 04:41:06 INFO - PROCESS | 25743 | RSS: Main: 222621696 04:41:06 INFO - PROCESS | 25743 | 04:41:07 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44809/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:08 INFO - PROCESS | 25743 | RSS: Main: 213532672 04:41:08 INFO - PROCESS | 25743 | 04:41:09 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:10 INFO - PROCESS | 25743 | RSS: Main: 214933504 04:41:10 INFO - PROCESS | 25743 | 04:41:12 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:13 INFO - PROCESS | 25743 | RSS: Main: 212312064 04:41:13 INFO - PROCESS | 25743 | 04:41:15 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:16 INFO - PROCESS | 25743 | RSS: Main: 225091584 04:41:16 INFO - PROCESS | 25743 | 04:41:18 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:19 INFO - PROCESS | 25743 | RSS: Main: 224972800 04:41:19 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | Cycle 1(1): loaded http://localhost:44809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44809/tests/kraken/driver.html?testName=ai-astar) 04:41:21 INFO - PROCESS | 25743 | RSS: Main: 267354112 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | __start_tp_report 04:41:21 INFO - PROCESS | 25743 | _x_x_mozilla_page_load 04:41:21 INFO - PROCESS | 25743 | _x_x_mozilla_page_load_details 04:41:21 INFO - PROCESS | 25743 | |i|pagename|runs| 04:41:21 INFO - PROCESS | 25743 | |0;ai-astar;103;112;110;109;111;113;112;111;111;111 04:41:21 INFO - PROCESS | 25743 | |1;audio-beat-detection;150;116;126;177;122;117;150;122;173;121 04:41:21 INFO - PROCESS | 25743 | |2;audio-dft;160;162;162;161;160;162;159;162;161;161 04:41:21 INFO - PROCESS | 25743 | |3;audio-fft;75;75;74;126;76;76;74;74;107;75 04:41:21 INFO - PROCESS | 25743 | |4;audio-oscillator;83;84;110;84;84;83;121;85;84;119 04:41:21 INFO - PROCESS | 25743 | |5;imaging-gaussian-blur;118;117;117;116;117;117;117;117;117;116 04:41:21 INFO - PROCESS | 25743 | |6;imaging-darkroom;146;145;146;146;146;145;146;145;145;145 04:41:21 INFO - PROCESS | 25743 | |7;imaging-desaturate;95;94;95;95;95;95;95;94;95;94 04:41:21 INFO - PROCESS | 25743 | |8;json-parse-financial;50;50;51;50;50;50;50;50;50;50 04:41:21 INFO - PROCESS | 25743 | |9;json-stringify-tinderbox;63;63;63;63;64;63;64;64;63;63 04:41:21 INFO - PROCESS | 25743 | |10;stanford-crypto-aes;80;75;78;82;76;75;75;81;74;79 04:41:21 INFO - PROCESS | 25743 | |11;stanford-crypto-ccm;117;127;116;118;119;115;121;122;170;135 04:41:21 INFO - PROCESS | 25743 | |12;stanford-crypto-pbkdf2;156;160;159;159;157;154;155;156;160;154 04:41:21 INFO - PROCESS | 25743 | |13;stanford-crypto-sha256-iterative;66;63;62;62;63;65;65;62;62;61 04:41:21 INFO - PROCESS | 25743 | __end_tp_report 04:41:21 INFO - PROCESS | 25743 | __start_cc_report 04:41:21 INFO - PROCESS | 25743 | _x_x_mozilla_cycle_collect,1687 04:41:21 INFO - PROCESS | 25743 | __end_cc_report 04:41:21 INFO - PROCESS | 25743 | __startTimestamp1468842081425__endTimestamp 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | ------- Summary: start ------- 04:41:21 INFO - PROCESS | 25743 | Number of tests: 14 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.79 (2.5%) stddev-sans-first:1.17 04:41:21 INFO - PROCESS | 25743 | Values: 103.0 112.0 110.0 109.0 111.0 113.0 112.0 111.0 111.0 111.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:138.00 stddev:23.33 (16.9%) stddev-sans-first:24.30 04:41:21 INFO - PROCESS | 25743 | Values: 150.0 116.0 126.0 177.0 122.0 117.0 150.0 122.0 173.0 121.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.05 (0.7%) stddev-sans-first:1.05 04:41:21 INFO - PROCESS | 25743 | Values: 160.0 162.0 162.0 161.0 160.0 162.0 159.0 162.0 161.0 161.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:18.13 (24.2%) stddev-sans-first:18.98 04:41:21 INFO - PROCESS | 25743 | Values: 75.0 75.0 74.0 126.0 76.0 76.0 74.0 74.0 107.0 75.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:84.00 stddev:16.10 (19.2%) stddev-sans-first:16.60 04:41:21 INFO - PROCESS | 25743 | Values: 83.0 84.0 110.0 84.0 84.0 83.0 121.0 85.0 84.0 119.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 04:41:21 INFO - PROCESS | 25743 | Values: 118.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 04:41:21 INFO - PROCESS | 25743 | Values: 146.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:41:21 INFO - PROCESS | 25743 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 04:41:21 INFO - PROCESS | 25743 | Values: 50.0 50.0 51.0 50.0 50.0 50.0 50.0 50.0 50.0 50.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 04:41:21 INFO - PROCESS | 25743 | Values: 63.0 63.0 63.0 63.0 64.0 63.0 64.0 64.0 63.0 63.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:78.50 stddev:2.88 (3.7%) stddev-sans-first:2.91 04:41:21 INFO - PROCESS | 25743 | Values: 80.0 75.0 78.0 82.0 76.0 75.0 75.0 81.0 74.0 79.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:121.50 stddev:16.58 (13.6%) stddev-sans-first:17.26 04:41:21 INFO - PROCESS | 25743 | Values: 117.0 127.0 116.0 118.0 119.0 115.0 121.0 122.0 170.0 135.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.00 Median:158.00 stddev:2.36 (1.5%) stddev-sans-first:2.47 04:41:21 INFO - PROCESS | 25743 | Values: 156.0 160.0 159.0 159.0 157.0 154.0 155.0 156.0 160.0 154.0 04:41:21 INFO - PROCESS | 25743 | 04:41:21 INFO - PROCESS | 25743 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.66 (2.6%) stddev-sans-first:1.39 04:41:21 INFO - PROCESS | 25743 | Values: 66.0 63.0 62.0 62.0 63.0 65.0 65.0 62.0 62.0 61.0 04:41:21 INFO - PROCESS | 25743 | -------- Summary: end -------- 04:41:21 INFO - PROCESS | 25743 | 04:41:22 INFO - TEST-INFO | 25743: exit 0 04:41:22 INFO - TEST-OK | kraken | took 61190ms 04:41:22 INFO - SUITE-END | took 1174s 04:41:22 INFO - Completed test suite (00:19:34) 04:41:22 INFO - javascript benchmark 04:41:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3072.554479600459, 3073.969260307397, 3091.845407729613, 3111.9533207001887, 3135.905922822316, 5279.947200527993, 5339.092354299769, 5361.51746342829, 5389.676619402839, 5469.097598896187, 5281.947180528194, 5285.656432331895, 5301.443348448415, 5314.867128321794, 5329.786808527654, 4741.620670346381, 4826.46908840029, 4832.71003739775, 4845.466998630165, 4964.230544265626, 4883.706977581356, 4893.926591101136, 4930.186519224341, 4931.408231012284, 4948.183549714293, 4739.454962679284, 4746.90506189876, 4753.643476739258, 4847.75761211938, 4911.895920045856, 4845.345878306419, 4855.6115510759055, 4863.367762190928, 4914.483979182206, 4969.453360130383, 4902.068606964701, 4902.117618828609, 4904.386951631046, 4907.2393778964315, 4912.484189160123, 5238.266642670029, 5247.10799164143, 5308.787648494055, 5369.516743493065, 5371.167469042305, 5672.943270567324, 5711.62874413166, 5726.455986681259, 5739.684317362543, 5742.0, 5455.0453670607485, 5717.485426311652, 5770.913436298459, 5775.162601422812, 5776.277965254343, 5234.450382709837, 5246.973765131188, 5257.658252213594, 5344.465553444663, 5347.946520534822, 5824.621399609012, 5827.446392592697, 5832.970835145882, 5838.095095260242, 5843.152742852262, 5418.945810541923, 5550.777968881281, 5607.0, 5619.213310136584, 5625.043742563774, 5629.2681951346885, 5769.1634712967225, 5771.192033115367, 5775.913361299584, 5779.3642699303045, 5686.34607020198, 5692.430756924275, 5693.886122277531, 5694.117411801178, 5707.514861236758, 5493.862653433616, 5537.58468114893, 5557.888842223133, 5696.51579615729, 5705.6291341063525, 4281.785910704452, 4289.571042895748, 4297.763623000703, 4324.416203812445, 4336.609705126553, 3822.10180607557, 3849.403342481864, 3865.111024464333, 3880.1269714314617, 3896.493455850778, 5313.1764576490705, 5319.468053194727, 5352.277442545284, 5383.67697938125, 5438.483344082306, 5761.654300741969, 5779.711014449261, 5783.479486846204, 5787.045137552257, 5822.737976791054, 5474.42518535556, 5477.835664930059, 5491.505764481136, 5491.560675145979, 5494.340679118532, 4788.425388953278, 4898.657094003457, 4971.080350135164, 4971.080350135237, 5001.324821149171, 5896.056630939115, 5903.468687818117, 5905.291365036137, 5908.20239267702, 5942.0, 5569.470900264469, 5766.221560089334, 5778.56660750437, 5784.681842498703, 5785.161151633074, 5894.557908156819, 5913.70431478433, 5916.3492015877355, 5952.255968003999, 5970.641761494324, 5596.328440586993, 5597.720113994365, 5597.748101335368, 5602.383737788921, 5647.632903861236, 5445.101558242845, 5614.494695477427, 5623.915641265467, 5676.744546495335, 5682.374938756816, 5527.0, 5539.778408863762, 5547.140193270049, 5583.69289689071, 5651.547876169814, 5591.077472217039, 5594.41258667834, 5597.468240517144, 5607.943920560905, 5608.242887210175, 5447.754851031634, 5450.236966824568, 5456.7544460498575, 5498.862528436821, 5613.0, 5576.553875690016, 5622.915656265078, 5628.268325117709, 5630.718464076862, 5637.3235211774845, 5428.402875683592, 5437.993971115198, 5441.646292990943, 5449.972750136224, 5450.890982180414], "value": 5256.597659075538, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.5563327504372, 602.1990752299441, 626.7242413338133, 632.2286810091689, 633.83837121534, 32985.50521742173, 36066.81966590167, 36121.277574448526, 42116.57883421165, 43503.56496435035, 44922.55077449224, 45512.317315240216, 45562.544374556244, 45566.772166139126, 45753.542464575345, 8923.91076089239, 9053.094690530927, 9058.320625953047, 9073.863892041625, 9076.500792456412, 8867.068957759438, 8943.95528022359, 8950.41822281553, 8952.149545793172, 8955.596998135066, 8964.282857371394, 8985.326100542452, 9001.549922503882, 9005.0, 9007.909920900773, 8883.644654213824, 8924.509151996637, 8924.732258032205, 8969.416987895798, 8971.551422428853, 8949.463032218087, 9008.0, 9016.729498115066, 9018.143276388799, 9018.549072546411, 42414.36378454295, 43252.567474325166, 43274.350884736756, 43275.0, 43278.35082473765, 3491.793240437907, 3572.910677233064, 3615.7649752765988, 3634.0914771307175, 3745.101175717808, 32410.675893241, 32740.17456383918, 32899.50650740241, 33371.66628333734, 34144.48783268253, 4376.9343459847805, 4420.359047938063, 4433.467983841927, 4451.688381813275, 4524.954750452486, 352092.0, 352317.23841380887, 352590.0, 355196.0, 355551.0, 60627.69686151541, 60723.392766071775, 60809.391906081255, 60813.0, 60864.69567652133, 54202.0, 60481.69759151176, 61824.690876545326, 62158.68920655458, 67700.6614966922, 15349.6162595936, 15401.845981540266, 15448.150351730537, 15452.768208476882, 15455.30451129702, 11127.332360058423, 11445.48495317696, 11451.141164412702, 11488.885111148782, 11495.597654082067, 17451.38920137789, 18000.9099954502, 18015.369462069026, 18123.637527249382, 18572.349967751063, 6405.420124610926, 6411.551191416556, 6457.644829534421, 6597.703103360361, 6631.0, 990.9752256193509, 1062.219268837404, 1163.2555164694602, 1167.5563285951455, 1186.602488166474, 33041.834790825895, 33062.0, 33141.83429082839, 33232.833835830665, 33650.158746031055, 1203.3201241298639, 1205.73473835756, 1210.8244304575787, 1211.6062279759115, 1236.7341021680381, 17253.482395528154, 17538.473845784647, 17668.646627067643, 17713.557161071076, 17795.454204785594, 149843.25078374756, 150631.0, 151143.0, 152106.0, 153130.0, 59668.0, 59774.0, 59790.50523736941, 59825.401745982854, 59906.0, 1246.3954981833804, 1338.2639548248424, 1389.3817251323064, 1389.7567925612977, 1395.8045873577707, 32650.183745405604, 34047.0, 34360.48459273063, 34378.82810585931, 34603.13492162716, 50616.0, 51078.0, 51603.0, 51608.48391516037, 51662.225066624786, 374577.0, 377272.9318008, 378127.0, 378825.0, 379441.0, 27599.448011039265, 28700.282492937844, 28725.138245852657, 30021.0, 30026.54960175643, 49272.0, 49646.25530616971, 49965.0, 51106.0, 51359.0, 41297.380539291335, 42104.57895421129, 42888.0, 43686.56313436825, 43835.12329753451, 41682.79158604187, 42368.364474533526, 42613.36079958865, 43215.78392108019, 43367.34948975705, 41742.58257417508, 42418.5758142427, 42909.0, 43357.0, 43387.132257355304, 36439.0, 37408.251834963696, 38037.80981095077, 38140.809295953346, 38163.42754858735, 28642.57036144501, 28678.426431470838, 28721.42557148887, 28731.5690264642, 28758.0], "value": 19554.863533897093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.7372912690242, 399.4308110941907, 404.45600667102747, 406.7864371205117, 425.291889004807, 12002.51989920402, 14300.0, 19022.0, 20270.29053983111, 20271.0, 21109.788902110973, 21749.238776642822, 22406.439839004033, 22495.887520562414, 22714.88642556785, 6472.0, 6594.043863639781, 6741.426978706816, 6815.522913396064, 6816.965915170417, 6451.2903580606335, 6700.028495868096, 6708.664566771642, 6720.1599800025, 6762.120924279837, 6718.59688418694, 6742.157230346207, 6749.0551322814845, 6753.45972322213, 6758.290379510155, 6695.631740254284, 6698.564593301444, 7394.889076663854, 7762.0, 7769.728059517946, 7314.414846812242, 7516.361109305715, 7556.282153195492, 7563.886541701879, 7611.619419029026, 25833.612495812577, 26778.196654100408, 27335.0, 27427.31431714203, 27718.0, 2442.8629403438595, 2509.3099397665605, 2548.706898706654, 2582.5093232285994, 3263.184203949013, 15275.0, 15691.1369874658, 16347.182640868028, 16396.426125085563, 17102.401415950506, 2085.937421877346, 2111.3771437425844, 2176.9891150544145, 2177.912883484659, 2227.454273702937, 95029.52485237598, 95279.52360238224, 97182.0, 98324.50837745838, 102650.48674756654, 16989.660206795794, 17773.289068437116, 17826.821731782777, 17977.820221797876, 18017.2793088275, 32348.0, 32509.51235731466, 32562.67437325644, 33111.0, 33125.17187070294, 9793.51032448389, 10007.749806254902, 10072.798544029078, 10137.341072830242, 10267.383956962607, 8124.634391452399, 8150.38872084596, 8233.79415514617, 8284.668613255522, 8447.493150410872, 8872.24585910205, 9028.954855225682, 9145.131212534798, 9145.176934075966, 9221.123993220634, 3263.3636440894275, 3573.2674801666117, 3591.2817436512278, 3667.94498082529, 3705.8118633912736, 906.5467266366817, 947.218544700611, 968.5786682793008, 977.2426369563645, 990.9108180263667, 19056.71414928777, 20549.48626284325, 20628.69056964147, 20701.89649051745, 20732.170713171305, 983.2085171436919, 988.5106872098214, 988.8467287570438, 1021.6015753856002, 1059.3485006720966, 10059.497025148714, 11040.282381645167, 11089.5564177432, 11095.167862410352, 11165.665030049111, 453346.0, 461117.1664849993, 467366.0, 473720.0, 475360.0, 97926.0, 98472.0, 98830.5058474703, 99462.0, 101488.49255753822, 978.3933960944261, 992.8361820299714, 1014.4065721552827, 1016.7102375822855, 1017.0338178730236, 17638.911805441403, 18019.0, 18136.274549017893, 18231.817681823544, 18518.0740962954, 42195.36706949337, 44115.33826992662, 44845.775771120934, 45572.77213613911, 46039.07921841477, 53381.733091334296, 62135.68932155491, 62573.37426625675, 63865.68067159635, 64056.67971660112, 8277.420580559303, 8654.22112009922, 8669.176428239307, 8677.0, 8767.912320876709, 7373.741919033023, 10943.85089565616, 12061.216020958609, 12646.0, 13589.32053397346, 11528.827067593826, 11638.53445862156, 11654.184207105422, 11886.227395219283, 12088.818667720167, 11915.40422978865, 11982.400879955792, 11988.0, 12065.939670301592, 12318.322492263012, 12010.399480026139, 12061.15571909993, 12114.0, 12385.380730963596, 12489.750204996031, 7714.3442807362, 7758.728444504415, 8674.392792504465, 8839.381243312906, 8957.059508751749, 11350.829737554108, 11538.653840384466, 11818.349990750592, 11871.228370155912, 12275.631731048083], "value": 10590.333903977578, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.1471015904897, 1457.577302582251, 1521.9954829812323, 1642.0, 1752.8772985890994, 8417.957910210447, 8807.603657835396, 8844.823103537925, 8976.910230897689, 9063.184313411792, 8846.380753347268, 9025.0, 9027.052159523255, 9027.142421469965, 9029.548522573878, 2399.424138206831, 2424.7575242475787, 2425.514897020594, 2426.417659761662, 2428.429319110008, 2432.19737486629, 2448.24104527597, 2472.777450029497, 2477.0339567568662, 2479.913203037894, 2331.9533609327805, 2336.19401306549, 2360.5396947595227, 2367.254314890804, 2369.514249578841, 2309.457277539777, 2337.8947947342413, 2339.9298021059312, 2363.5863723848342, 2363.6927199464044, 2272.1706577099403, 2341.461463863304, 2342.8828558572004, 2348.0255693887016, 2348.3072493614427, 7817.882731758971, 8547.957260213721, 8971.102889710977, 8999.415038022573, 9056.728298151065, 5289.497497737709, 5404.189371594253, 5414.945850541483, 5423.267858839045, 5424.755885985101, 3529.6117427083, 3579.9642003579893, 3619.6380361963857, 3640.7451478396524, 3644.5808731995894, 1179.2099293473393, 1186.6499382682043, 1195.396324855951, 1214.4413569757921, 1223.4739062203248, 12478.251304921643, 12574.182678125984, 12575.05687073464, 12599.622011339674, 12611.558595449114, 4493.191225579427, 4528.977355113269, 4656.3946686930485, 4659.5573420525, 4737.620990320766, 10393.740156496147, 10464.947675261727, 10471.895281047244, 10473.371597704163, 10475.84286235707, 5392.865178370572, 5424.7016414096815, 5424.810131645373, 5427.3215848019, 5430.429804870431, 4820.30105634681, 4983.775730092155, 5000.974995125074, 5001.549860512571, 5021.121303771825, 3789.1853251551047, 3848.3265428549894, 3851.114243723959, 3880.1269714314617, 3942.842286308573, 1126.284809146198, 1133.4502766158407, 1142.948567314473, 1146.7706458708292, 1167.2646232873235, 1024.2318261304022, 1024.2420608749471, 1026.8408396698524, 1028.6502589119586, 1033.9017793309624, 6076.787312444132, 6160.476359509402, 6214.5255416100335, 6287.402696743802, 6364.49084073273, 469.840254313535, 471.0555336550195, 477.3388856433864, 484.79880849447085, 497.54226111977067, 4234.894127646772, 4414.580614841585, 4425.3140763181755, 4435.822567097344, 4491.932621010687, 10908.727281818017, 11241.988221060144, 11257.099432045417, 11316.943415283036, 11317.886821131848, 11231.045361144232, 11232.382218978035, 11244.763076061627, 11246.66260012201, 11248.606298779667, 7760.185180556074, 8051.758447246709, 8065.153158918347, 8423.536705481258, 8500.829983400297, 6224.595401298901, 6259.186305780219, 6356.687452881551, 6413.967930160319, 6470.708818103102, 10311.793764124826, 10381.169506439315, 10383.494393312776, 10492.160627149962, 10493.265471417233, 10455.47722613851, 10687.679369618923, 10781.730456738644, 10809.243352965532, 10812.405317707602, 7539.283768042027, 7618.8095297618, 7633.618319084135, 7645.541267523967, 7694.422918281263, 8153.918460815553, 8164.550949697824, 8184.754457366289, 8216.712415065444, 8217.712380066669, 7426.405887529093, 7544.962275188589, 7559.092908850973, 7608.733694320672, 7672.424568157522, 7214.314640109181, 7401.444891633041, 7442.404607631268, 7542.208068152765, 7586.734464293723, 7051.294870512907, 7080.079589653312, 7097.893531596927, 7099.325564071405, 7108.182559005781, 5085.135526960352, 5271.341082364705, 5358.115910874662, 5374.435684253253, 5410.242566040679, 5383.650062745909, 5446.373667028342, 5452.863678407913, 5520.254765606592, 5643.858903527444], "value": 4638.456231845523, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.6729703944574, 330.9106541233867, 340.4552715654951, 340.798928632107, 342.2316898562726, 5293.861819708762, 5311.282976798136, 5335.306410166682, 5353.7953960359, 5366.4633536646315, 4993.575546078579, 5095.566876815466, 5251.159814429692, 5284.577233821295, 5288.867778305545, 4129.442525259096, 4160.355144952538, 4183.205191013698, 4187.497500299968, 4201.705880588368, 4163.646090082347, 4166.729162604436, 4167.312393455076, 4172.290710579209, 4187.1416359646355, 3892.5523564789987, 3948.7038472114564, 4029.6171863672903, 4119.217348703752, 4159.0, 4136.772477513736, 4150.564190759972, 4157.896052598679, 4161.167766446722, 4162.708610397258, 3694.2796154749835, 3697.3159965406485, 3706.4803430593597, 3710.591834898159, 3723.83242754074, 5112.590992720573, 5125.6155788315655, 5148.065602606478, 5153.304303918998, 5214.661047031931, 4713.5993440557595, 4718.834840780556, 4722.929156062662, 4739.526047395268, 4742.739149346785, 5536.4186760390385, 5569.162176481751, 5601.123623586103, 5603.10350343943, 5603.775848966036, 3907.7459965102175, 4032.475778148851, 4082.9183416331507, 4087.7925920003163, 4087.9591204087874, 5747.770089196469, 5782.9012487627215, 5791.449812267828, 5796.333421656522, 5800.738966746507, 4194.6853985950565, 4195.056112374752, 4201.45381100455, 4204.642605378516, 4205.474315710536, 3961.9405708914387, 4170.332746760504, 4188.958110418917, 4236.237477254123, 4258.0, 4730.763461826964, 4738.3129446230105, 4770.0936822003705, 4771.093492236464, 4780.904381912412, 5548.778048878, 5564.777408903679, 5590.329160500766, 5593.804216852391, 5596.265157801095, 1352.5468967895865, 1355.3155656393656, 1422.8506006869336, 1429.121090529317, 1437.015644283669, 949.1884438804723, 982.9705108846746, 993.6571882700457, 995.9352642078242, 1000.8048430555972, 5212.55693266069, 5330.734437863893, 5345.305110335631, 5407.513323800877, 5407.810726624549, 5620.269364982526, 5624.3813180550105, 5637.154426836008, 5645.23789288449, 5656.359655699884, 5260.395054568697, 5297.0, 5303.549198318109, 5320.627556071116, 5330.120530112488, 2891.79757416983, 2947.985260073686, 2951.9262018449713, 3018.351054523288, 3028.772842036856, 5881.558883083786, 5918.691710232926, 5920.2895652521975, 5929.466348028698, 5935.52515798735, 5631.324241091095, 5858.8242352729485, 5859.296884373903, 5895.616784908967, 5916.319623243296, 5864.912026319608, 5887.8233652990475, 5899.584099816099, 5916.437938395846, 5925.874083924041, 5690.231818704422, 5726.255586773693, 5733.799317023884, 5770.855728606818, 5793.91309130355, 5384.380796208487, 5643.1535269709875, 5674.148877668383, 5690.260266165372, 5706.172604972173, 5915.792947246826, 5940.584159108821, 5950.553708471795, 5973.313068997121, 5975.237304995124, 4093.5087789465456, 4117.444145040501, 4148.108156746193, 4150.667946564328, 4157.08544120293, 5660.575456840836, 5727.455891690282, 5754.827355179351, 5755.510781583613, 5776.162456443708, 5360.43715409892, 5598.552115830642, 5601.6639001658405, 5610.4670056344585, 5613.522850557748, 5462.399136094947, 5493.176023596333, 5497.890042199054, 5503.174523821459, 5586.972065139648, 5584.766093562607, 5628.268325117709, 5649.0, 5661.631993920382, 5674.2623458950075, 5539.14143307788, 5562.332520097614, 5653.660780353192, 5681.318241811009, 5692.971535142297, 5500.80747173847, 5535.197396377584, 5554.305711786027, 5556.194351819045, 5574.163875418558], "value": 4267.078515352044, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.6617354537553, 806.3831169155598, 817.5830326533467, 824.4270232188629, 861.7931696392864, 15016.549503514898, 16611.750823737653, 16644.08457534835, 16709.582260443494, 16745.07902065386, 15015.624609384771, 15897.284622192, 15926.442574509894, 16361.590960226, 16367.4271400501, 678.6403206300669, 814.4298990706515, 827.2843989948711, 831.6132998155856, 832.5587438657491, 694.482610455209, 708.472188219777, 708.7058870568708, 810.6149578949982, 829.6432534010373, 868.2663149638545, 871.1767379826067, 871.3203701113142, 871.376965469689, 872.5419154943673, 863.9395242333039, 864.0840708848641, 868.2012548455436, 869.3827382558391, 869.5000374784524, 514.2414937966507, 621.3382747374077, 634.6890023888308, 637.0380725105078, 639.9072134540465, 15300.234988250542, 15367.538973830691, 15392.230388480531, 15393.384264629402, 15427.768583471257, 13455.461781528727, 13498.325083745773, 14328.283585820667, 14343.282835858165, 14371.92814035923, 12993.935030324883, 13019.47922083125, 13103.86896131036, 13317.533886313933, 13385.86614133856, 3848.730588858781, 3919.6074450754923, 4013.097053163044, 4013.1572369802375, 4045.1707399982934, 78043.6097819513, 78312.21687783163, 79702.60148699277, 79779.0, 79822.60088699519, 11479.942600286946, 11492.310461372344, 11765.411729413496, 11794.410279485992, 11897.762044759229, 22180.556388872134, 22406.215782447536, 22615.660765088538, 22682.0, 22758.431039223822, 8883.629503829778, 8898.822023559493, 8904.19862212404, 9059.094090590888, 9105.81788364229, 4662.7202367858035, 4719.339292499053, 4772.6420518461255, 4874.683145595525, 4940.110780059624, 3466.9479957800654, 3621.4748861415474, 3629.510016147839, 3660.3228402745576, 3661.1579336752698, 574.0642752313786, 577.4485366475091, 593.1429084972174, 593.8545056461195, 605.5428151745404, 741.9554826710414, 766.8044648614567, 767.9347255483347, 784.4234487651571, 788.932940700047, 9925.255605829592, 10110.949445252874, 10121.291509594404, 10279.588816447407, 10350.896491034993, 634.3475246981639, 735.5386257041446, 759.0966749567997, 773.1225059557443, 783.8863364812071, 4765.571098601143, 4929.605631549467, 4999.950000500021, 5090.567301779316, 5100.0, 112347.4382628098, 114880.0, 115034.0, 115976.24261331247, 117148.0, 64933.67533162304, 70601.64699176472, 85305.0, 93695.53152234331, 95319.52340238393, 78302.0, 84807.5759621198, 84919.72620410699, 84959.5752021236, 85683.0, 10464.686059418229, 10578.626936593637, 10749.355038697704, 10806.189535785004, 10842.295250808673, 49669.75165124296, 49686.75156624339, 50299.0, 50976.23535646894, 51943.74028129835, 45044.0, 50015.749921250164, 50582.74708626433, 62318.376816231255, 74845.6257718708, 13100.60698179056, 13396.732065358834, 13460.461581536629, 13580.93209533946, 13717.794233086712, 15134.772978405112, 15588.610284742972, 15606.84393156054, 15906.363745450515, 16182.676346472768, 10778.407277369957, 14425.134491930517, 14571.635709107357, 14604.488842890449, 14642.121472711671, 13873.722525549634, 14112.858871411154, 14364.947584942216, 14399.178841209115, 15187.392504299703, 13947.58157255284, 14252.786208207093, 14272.14367137975, 14610.488632897797, 14647.26763661834, 10980.615678451215, 11555.942220288845, 11732.354720490455, 12226.266424014588, 12418.627441176779, 11762.411879406167, 12139.0, 12183.208091474025, 12526.622071571937, 12652.936735316265], "value": 7684.219366179201, "unit": "score"}], "name": "dromaeo_css", "value": 7410.312505728158, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 110.0, 109.0, 111.0, 113.0, 112.0, 111.0, 111.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 126.0, 177.0, 122.0, 117.0, 150.0, 122.0, 173.0, 121.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 161.0, 160.0, 162.0, 159.0, 162.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 126.0, 76.0, 76.0, 74.0, 74.0, 107.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 110.0, 84.0, 84.0, 83.0, 121.0, 85.0, 84.0, 119.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 64.0, 64.0, 63.0, 63.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 78.0, 82.0, 76.0, 75.0, 75.0, 81.0, 74.0, 79.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 127.0, 116.0, 118.0, 119.0, 115.0, 121.0, 122.0, 170.0, 135.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 160.0, 159.0, 159.0, 157.0, 154.0, 155.0, 156.0, 160.0, 154.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 62.0, 62.0, 63.0, 65.0, 65.0, 62.0, 62.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1479.7, "alertThreshold": 2.0}]} 04:41:22 INFO - Return code: 0 04:41:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:41:22 INFO - # TBPL SUCCESS # 04:41:22 INFO - Running post-action listener: _resource_record_post_action 04:41:22 INFO - ##### 04:41:22 INFO - ##### Finished run-tests step (success) 04:41:22 INFO - ##### 04:41:22 INFO - Running post-run listener: _resource_record_post_run 04:41:22 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 553336832; Read time: 8; Write time: 550600 04:41:22 INFO - TinderboxPrint: CPU usage
13.0% 04:41:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 04:41:22 INFO - TinderboxPrint: I/O write bytes / time
553,336,832 / 550,600 04:41:22 INFO - TinderboxPrint: CPU idle
8,275.0 (87.0%) 04:41:22 INFO - TinderboxPrint: CPU user
1,193.9 (12.5%) 04:41:22 INFO - TinderboxPrint: Swap in / out
0 / 0 04:41:22 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 264028160; Read time: 0; Write time: 267488 04:41:22 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 289308672; Read time: 0; Write time: 283112 04:41:23 INFO - Running post-run listener: _upload_blobber_files 04:41:23 INFO - Blob upload gear active. 04:41:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:41:23 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 04:41:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:41:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:41:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:41:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:41:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:35 INFO - (blobuploader) - INFO - Done attempting. 04:41:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:41:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:41:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:36 INFO - (blobuploader) - INFO - Done attempting. 04:41:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:41:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:41:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:36 INFO - (blobuploader) - INFO - Done attempting. 04:41:36 INFO - (blobuploader) - INFO - Iteration through files over. 04:41:36 INFO - Return code: 0 04:41:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:41:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:41:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02262d6b9c8ade9ad095820b0f896f0f8f643df1c2dc4f91e1f973c61e2b4a2f43eb37804ca1d39e55eed64742adccd819b6e33e168b8eaf033b3bab49b0f659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fa4a8e524c828a412b197edef493aa3c3fce407fa707cb3af2d54494a5216168cae929dfd2e035a5dcfc1a492c6ced1fce37a4d250792c6e1d63343f35ed4e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27d8f7a735c1ac41bcb23d7c71f41bd98f6b108424e1c6bce3f7b121d1e7c039a22f5fe48314c8bd46f9c5d02c3e6543288abb1b6066e1f013907c6a590262eb"} 04:41:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:41:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:41:36 INFO - Contents: 04:41:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02262d6b9c8ade9ad095820b0f896f0f8f643df1c2dc4f91e1f973c61e2b4a2f43eb37804ca1d39e55eed64742adccd819b6e33e168b8eaf033b3bab49b0f659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fa4a8e524c828a412b197edef493aa3c3fce407fa707cb3af2d54494a5216168cae929dfd2e035a5dcfc1a492c6ced1fce37a4d250792c6e1d63343f35ed4e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27d8f7a735c1ac41bcb23d7c71f41bd98f6b108424e1c6bce3f7b121d1e7c039a22f5fe48314c8bd46f9c5d02c3e6543288abb1b6066e1f013907c6a590262eb"} 04:41:36 INFO - Running post-run listener: copy_logs_to_upload_dir 04:41:36 INFO - Copying logs to upload dir... 04:41:36 INFO - mkdir: /builds/slave/test/build/upload/logs 04:41:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.709600 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-18 04:41:33.555408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 04:41:33.558754) ========= 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=fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02262d6b9c8ade9ad095820b0f896f0f8f643df1c2dc4f91e1f973c61e2b4a2f43eb37804ca1d39e55eed64742adccd819b6e33e168b8eaf033b3bab49b0f659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fa4a8e524c828a412b197edef493aa3c3fce407fa707cb3af2d54494a5216168cae929dfd2e035a5dcfc1a492c6ced1fce37a4d250792c6e1d63343f35ed4e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27d8f7a735c1ac41bcb23d7c71f41bd98f6b108424e1c6bce3f7b121d1e7c039a22f5fe48314c8bd46f9c5d02c3e6543288abb1b6066e1f013907c6a590262eb"} build_url:https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011413 build_url: 'https://queue.taskcluster.net/v1/task/DRsE8QyhQp-Diue66PX13g/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/Mozilla-Inbound/sha512/02262d6b9c8ade9ad095820b0f896f0f8f643df1c2dc4f91e1f973c61e2b4a2f43eb37804ca1d39e55eed64742adccd819b6e33e168b8eaf033b3bab49b0f659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fa4a8e524c828a412b197edef493aa3c3fce407fa707cb3af2d54494a5216168cae929dfd2e035a5dcfc1a492c6ced1fce37a4d250792c6e1d63343f35ed4e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27d8f7a735c1ac41bcb23d7c71f41bd98f6b108424e1c6bce3f7b121d1e7c039a22f5fe48314c8bd46f9c5d02c3e6543288abb1b6066e1f013907c6a590262eb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 04:41:33.584356) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:41:33.584691) ========= 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=fa44defc139c4ba8574fe040000001db-1468840856.210081-1772365231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:41:33.668297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 04:41:33.668613) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 04:41:33.668952) ========= ========= Total master_lag: 0.16 =========