builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1468633486.11 results: success (0) buildid: 20160715170951 builduid: 2ff1ad790e54413a8eb92b542b67ce85 revision: 1b3826196e7efc0c7442a02207ef833003de2dfe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.107423) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.108125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.108438) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.125666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.126301) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 18:44:46-- 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% 42.7M=0s 2016-07-15 18:44:46 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117611 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.260081) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.260752) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014137 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:46.310520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 18:44:46.310925) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1b3826196e7efc0c7442a02207ef833003de2dfe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 1b3826196e7efc0c7442a02207ef833003de2dfe --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=a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 18:44:46,336 truncating revision to first 12 chars 2016-07-15 18:44:46,336 Setting DEBUG logging. 2016-07-15 18:44:46,336 attempt 1/10 2016-07-15 18:44:46,336 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1b3826196e7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 18:44:47,318 unpacking tar archive at: autoland-1b3826196e7e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.322194 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 18:44:47.647242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:47.647585) ========= script_repo_revision: 1b3826196e7efc0c7442a02207ef833003de2dfe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:47.647982) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:47.648314) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 18:44:47.659111) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-15 18:44:47.659595) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:44:47 INFO - MultiFileLogger online at 20160715 18:44:47 in /builds/slave/test 18:44:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 18:44:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:44:47 INFO - {'append_to_log': False, 18:44:47 INFO - 'base_work_dir': '/builds/slave/test', 18:44:47 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 18:44:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:44:47 INFO - 'branch': 'Autoland-Non-PGO', 18:44:47 INFO - 'buildbot_json_path': 'buildprops.json', 18:44:47 INFO - 'config_files': ('talos/linux_config.py',), 18:44:47 INFO - 'default_actions': ('clobber', 18:44:47 INFO - 'read-buildbot-config', 18:44:47 INFO - 'download-and-extract', 18:44:47 INFO - 'populate-webroot', 18:44:47 INFO - 'create-virtualenv', 18:44:47 INFO - 'install', 18:44:47 INFO - 'run-tests'), 18:44:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:44:47 INFO - 'download_minidump_stackwalk': True, 18:44:47 INFO - 'download_symbols': 'ondemand', 18:44:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:44:47 INFO - 'tooltool.py': '/tools/tooltool.py', 18:44:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:44:47 INFO - '/tools/misc-python/virtualenv.py')}, 18:44:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:44:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:44:47 INFO - 'installer_path': 'installer.exe', 18:44:47 INFO - 'log_level': 'info', 18:44:47 INFO - 'log_name': 'talos', 18:44:47 INFO - 'log_to_console': True, 18:44:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:44:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:44:47 INFO - 'opt_config_files': (), 18:44:47 INFO - 'pip_index': False, 18:44:47 INFO - 'sps_profile': False, 18:44:47 INFO - 'sps_profile_interval': 0, 18:44:47 INFO - 'suite': 'dromaeojs', 18:44:47 INFO - 'system_bits': '32', 18:44:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:44:47 INFO - 'title': 'talos-linux64-ix-002', 18:44:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:44:47 INFO - 'use_talos_json': True, 18:44:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:44:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:44:47 INFO - 'work_dir': 'build'} 18:44:47 INFO - ##### 18:44:47 INFO - ##### Running clobber step. 18:44:47 INFO - ##### 18:44:47 INFO - Running pre-action listener: _resource_record_pre_action 18:44:47 INFO - Running main action method: clobber 18:44:47 INFO - rmtree: /builds/slave/test/build 18:44:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:44:48 INFO - Running post-action listener: _resource_record_post_action 18:44:48 INFO - ##### 18:44:48 INFO - ##### Finished clobber step (success) 18:44:48 INFO - ##### 18:44:48 INFO - ##### 18:44:48 INFO - ##### Running read-buildbot-config step. 18:44:48 INFO - ##### 18:44:48 INFO - Running pre-action listener: _resource_record_pre_action 18:44:48 INFO - Running main action method: read_buildbot_config 18:44:48 INFO - Using buildbot properties: 18:44:48 INFO - { 18:44:48 INFO - "project": "", 18:44:48 INFO - "product": "firefox", 18:44:48 INFO - "script_repo_revision": "production", 18:44:48 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 18:44:48 INFO - "repository": "", 18:44:48 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 18:44:48 INFO - "buildid": "20160715170951", 18:44:48 INFO - "pgo_build": "False", 18:44:48 INFO - "basedir": "/builds/slave/test", 18:44:48 INFO - "buildnumber": 55, 18:44:48 INFO - "slavename": "talos-linux64-ix-002", 18:44:48 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:44:48 INFO - "platform": "ubuntu64_hw", 18:44:48 INFO - "scheduler": "tests-autoland-linux64-talos", 18:44:48 INFO - "branch": "autoland", 18:44:48 INFO - "repo_path": "integration/autoland", 18:44:48 INFO - "revision": "1b3826196e7efc0c7442a02207ef833003de2dfe", 18:44:48 INFO - "stage_platform": "linux64", 18:44:48 INFO - "builduid": "2ff1ad790e54413a8eb92b542b67ce85", 18:44:48 INFO - "slavebuilddir": "test" 18:44:48 INFO - } 18:44:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:44:48 INFO - Running post-action listener: _resource_record_post_action 18:44:48 INFO - ##### 18:44:48 INFO - ##### Finished read-buildbot-config step (success) 18:44:48 INFO - ##### 18:44:48 INFO - ##### 18:44:48 INFO - ##### Running download-and-extract step. 18:44:48 INFO - ##### 18:44:48 INFO - Running pre-action listener: _resource_record_pre_action 18:44:48 INFO - Running main action method: download_and_extract 18:44:48 INFO - mkdir: /builds/slave/test/build/tests 18:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:48 INFO - https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:44:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:44:48 INFO - Downloaded 1361 bytes. 18:44:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:48 INFO - Using the following test package requirements: 18:44:48 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:44:48 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:44:48 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:44:48 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'jsshell-linux-x86_64.zip'], 18:44:48 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:44:48 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:44:48 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:44:48 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:44:48 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:44:48 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:44:48 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:48 INFO - https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:44:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:44:49 INFO - Downloaded 22757712 bytes. 18:44:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:44:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:44:51 INFO - Return code: 0 18:44:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:51 INFO - https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:44:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:44:52 INFO - Downloaded 22757712 bytes. 18:44:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:44:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:44:53 INFO - Return code: 0 18:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:53 INFO - https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:44:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:44:54 INFO - Downloaded 14403317 bytes. 18:44:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:44:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:44:55 INFO - Return code: 0 18:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:55 INFO - https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:44:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:44:56 INFO - Downloaded 57061963 bytes. 18:44:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:56 INFO - mkdir: /builds/slave/test/properties 18:44:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:44:56 INFO - Writing to file /builds/slave/test/properties/build_url 18:44:56 INFO - Contents: 18:44:56 INFO - build_url:https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:57 INFO - Running post-action listener: _resource_record_post_action 18:44:57 INFO - Running post-action listener: set_extra_try_arguments 18:44:57 INFO - ##### 18:44:57 INFO - ##### Finished download-and-extract step (success) 18:44:57 INFO - ##### 18:44:57 INFO - ##### 18:44:57 INFO - ##### Running populate-webroot step. 18:44:57 INFO - ##### 18:44:57 INFO - Running pre-action listener: _resource_record_pre_action 18:44:57 INFO - Running main action method: populate_webroot 18:44:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:44:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:44:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:44:57 INFO - '--tppagecycles', 18:44:57 INFO - '7'], 18:44:57 INFO - 'tests': ['tsvgm']}}, 18:44:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:44:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:44:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:44:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:44:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['damp', 'tps']}, 18:44:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'tests': ['damp', 'tps']}, 18:44:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['dromaeo_dom']}, 18:44:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:44:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['basic_compositor_video']}, 18:44:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:44:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['a11yr', 18:44:57 INFO - 'ts_paint', 18:44:57 INFO - 'tpaint', 18:44:57 INFO - 'sessionrestore', 18:44:57 INFO - 'sessionrestore_no_auto_restore', 18:44:57 INFO - 'tabpaint']}, 18:44:57 INFO - 'other-e10s': {'tests': ['a11yr', 18:44:57 INFO - 'ts_paint', 18:44:57 INFO - 'tpaint', 18:44:57 INFO - 'sessionrestore', 18:44:57 INFO - 'sessionrestore_no_auto_restore', 18:44:57 INFO - 'tabpaint']}, 18:44:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:44:57 INFO - 'ts_paint', 18:44:57 INFO - 'tpaint', 18:44:57 INFO - 'sessionrestore', 18:44:57 INFO - 'sessionrestore_no_auto_restore', 18:44:57 INFO - 'tabpaint']}, 18:44:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:44:57 INFO - 'ts_paint', 18:44:57 INFO - 'tpaint', 18:44:57 INFO - 'sessionrestore', 18:44:57 INFO - 'sessionrestore_no_auto_restore', 18:44:57 INFO - 'tabpaint']}, 18:44:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['a11yr', 18:44:57 INFO - 'ts_paint', 18:44:57 INFO - 'tpaint', 18:44:57 INFO - 'sessionrestore', 18:44:57 INFO - 'sessionrestore_no_auto_restore', 18:44:57 INFO - 'tabpaint']}, 18:44:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['a11yr', 18:44:57 INFO - 'ts_paint', 18:44:57 INFO - 'tpaint', 18:44:57 INFO - 'sessionrestore', 18:44:57 INFO - 'sessionrestore_no_auto_restore', 18:44:57 INFO - 'tabpaint']}, 18:44:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['tsvgx', 18:44:57 INFO - 'tsvgr_opacity', 18:44:57 INFO - 'tart', 18:44:57 INFO - 'tscrollx', 18:44:57 INFO - 'cart']}, 18:44:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:44:57 INFO - 'tsvgr_opacity', 18:44:57 INFO - 'tart', 18:44:57 INFO - 'tscrollx', 18:44:57 INFO - 'cart']}, 18:44:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'talos_options': ['--disable-e10s'], 18:44:57 INFO - 'tests': ['tp5o']}, 18:44:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'tests': ['tp5o']}, 18:44:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'talos_options': ['--disable-e10s', 18:44:57 INFO - '--xperf_path', 18:44:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:57 INFO - 'tests': ['tp5n']}, 18:44:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:57 INFO - 'talos_options': ['--xperf_path', 18:44:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:57 INFO - 'tests': ['tp5n']}}, 18:44:57 INFO - 'talos.zip': {'path': '', 18:44:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:44:57 INFO - Running post-action listener: _resource_record_post_action 18:44:57 INFO - ##### 18:44:57 INFO - ##### Finished populate-webroot step (success) 18:44:57 INFO - ##### 18:44:57 INFO - ##### 18:44:57 INFO - ##### Running create-virtualenv step. 18:44:57 INFO - ##### 18:44:57 INFO - Running pre-action listener: _resource_record_pre_action 18:44:57 INFO - Running main action method: create_virtualenv 18:44:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:44:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:44:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:44:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:44:57 INFO - Using real prefix '/usr' 18:44:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:44:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:44:59 INFO - Installing pip.................done. 18:44:59 INFO - Return code: 0 18:44:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:44: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')]} 18:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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')]} 18:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:59 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:44:59 INFO - Using env: {'DISPLAY': ':0', 18:44:59 INFO - 'HOME': '/home/cltbld', 18:44:59 INFO - 'LANG': 'en_US.UTF-8', 18:44:59 INFO - 'LANGUAGE': 'en_US:en', 18:44:59 INFO - 'LOGNAME': 'cltbld', 18:44:59 INFO - 'MAIL': '/var/mail/cltbld', 18:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:59 INFO - 'MOZ_NO_REMOTE': '1', 18:44:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:59 INFO - 'NO_EM_RESTART': '1', 18:44:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:59 INFO - 'PWD': '/builds/slave/test', 18:44:59 INFO - 'SHELL': '/bin/bash', 18:44:59 INFO - 'SHLVL': '1', 18:44:59 INFO - 'TERM': 'linux', 18:44:59 INFO - 'TMOUT': '86400', 18:44:59 INFO - 'USER': 'cltbld', 18:44:59 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:59 INFO - '_': '/tools/buildbot/bin/python'} 18:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:45:00 INFO - Cleaning up... 18:45:00 INFO - Return code: 0 18:45:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:45: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')]} 18:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45: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')]} 18:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:00 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:45:00 INFO - Using env: {'DISPLAY': ':0', 18:45:00 INFO - 'HOME': '/home/cltbld', 18:45:00 INFO - 'LANG': 'en_US.UTF-8', 18:45:00 INFO - 'LANGUAGE': 'en_US:en', 18:45:00 INFO - 'LOGNAME': 'cltbld', 18:45:00 INFO - 'MAIL': '/var/mail/cltbld', 18:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:00 INFO - 'MOZ_NO_REMOTE': '1', 18:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:00 INFO - 'NO_EM_RESTART': '1', 18:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:00 INFO - 'PWD': '/builds/slave/test', 18:45:00 INFO - 'SHELL': '/bin/bash', 18:45:00 INFO - 'SHLVL': '1', 18:45:00 INFO - 'TERM': 'linux', 18:45:00 INFO - 'TMOUT': '86400', 18:45:00 INFO - 'USER': 'cltbld', 18:45:00 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:00 INFO - '_': '/tools/buildbot/bin/python'} 18:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:00 INFO - Downloading/unpacking psutil>=3.1.1 18:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:45:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:02 INFO - Installing collected packages: psutil 18:45:02 INFO - Running setup.py install for psutil 18:45:02 INFO - building 'psutil._psutil_linux' extension 18:45:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:45:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:45:02 INFO - building 'psutil._psutil_posix' extension 18:45:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:45:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:45:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:02 INFO - Successfully installed psutil 18:45:02 INFO - Cleaning up... 18:45:03 INFO - Return code: 0 18:45:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:45:03 INFO - Using env: {'DISPLAY': ':0', 18:45:03 INFO - 'HOME': '/home/cltbld', 18:45:03 INFO - 'LANG': 'en_US.UTF-8', 18:45:03 INFO - 'LANGUAGE': 'en_US:en', 18:45:03 INFO - 'LOGNAME': 'cltbld', 18:45:03 INFO - 'MAIL': '/var/mail/cltbld', 18:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:03 INFO - 'MOZ_NO_REMOTE': '1', 18:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:03 INFO - 'NO_EM_RESTART': '1', 18:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:03 INFO - 'PWD': '/builds/slave/test', 18:45:03 INFO - 'SHELL': '/bin/bash', 18:45:03 INFO - 'SHLVL': '1', 18:45:03 INFO - 'TERM': 'linux', 18:45:03 INFO - 'TMOUT': '86400', 18:45:03 INFO - 'USER': 'cltbld', 18:45:03 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:03 INFO - '_': '/tools/buildbot/bin/python'} 18:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:45:05 INFO - Installing collected packages: mozsystemmonitor 18:45:05 INFO - Running setup.py install for mozsystemmonitor 18:45:05 INFO - Successfully installed mozsystemmonitor 18:45:05 INFO - Cleaning up... 18:45:06 INFO - Return code: 0 18:45:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:45: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')]} 18:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45: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')]} 18:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:06 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:45:06 INFO - Using env: {'DISPLAY': ':0', 18:45:06 INFO - 'HOME': '/home/cltbld', 18:45:06 INFO - 'LANG': 'en_US.UTF-8', 18:45:06 INFO - 'LANGUAGE': 'en_US:en', 18:45:06 INFO - 'LOGNAME': 'cltbld', 18:45:06 INFO - 'MAIL': '/var/mail/cltbld', 18:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:06 INFO - 'MOZ_NO_REMOTE': '1', 18:45:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:06 INFO - 'NO_EM_RESTART': '1', 18:45:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:06 INFO - 'PWD': '/builds/slave/test', 18:45:06 INFO - 'SHELL': '/bin/bash', 18:45:06 INFO - 'SHLVL': '1', 18:45:06 INFO - 'TERM': 'linux', 18:45:06 INFO - 'TMOUT': '86400', 18:45:06 INFO - 'USER': 'cltbld', 18:45:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:06 INFO - '_': '/tools/buildbot/bin/python'} 18:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:06 INFO - Downloading/unpacking jsonschema==2.5.1 18:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:45:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:45:08 INFO - warning: no files found matching '*.txt' 18:45:08 INFO - no previously-included directories found matching 'build' 18:45:08 INFO - no previously-included directories found matching 'dist' 18:45:08 INFO - no previously-included directories found matching '.git*' 18:45:08 INFO - Installing collected packages: jsonschema, functools32 18:45:08 INFO - Running setup.py install for functools32 18:45:08 INFO - warning: no files found matching '*.txt' 18:45:08 INFO - no previously-included directories found matching 'build' 18:45:08 INFO - no previously-included directories found matching 'dist' 18:45:08 INFO - no previously-included directories found matching '.git*' 18:45:08 INFO - Successfully installed jsonschema functools32 18:45:08 INFO - Cleaning up... 18:45:08 INFO - Return code: 0 18:45:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:08 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:45:08 INFO - Using env: {'DISPLAY': ':0', 18:45:08 INFO - 'HOME': '/home/cltbld', 18:45:08 INFO - 'LANG': 'en_US.UTF-8', 18:45:08 INFO - 'LANGUAGE': 'en_US:en', 18:45:08 INFO - 'LOGNAME': 'cltbld', 18:45:08 INFO - 'MAIL': '/var/mail/cltbld', 18:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:08 INFO - 'MOZ_NO_REMOTE': '1', 18:45:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:08 INFO - 'NO_EM_RESTART': '1', 18:45:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:08 INFO - 'PWD': '/builds/slave/test', 18:45:08 INFO - 'SHELL': '/bin/bash', 18:45:08 INFO - 'SHLVL': '1', 18:45:08 INFO - 'TERM': 'linux', 18:45:08 INFO - 'TMOUT': '86400', 18:45:08 INFO - 'USER': 'cltbld', 18:45:08 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:08 INFO - '_': '/tools/buildbot/bin/python'} 18:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:09 INFO - Downloading/unpacking blobuploader==1.2.4 18:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:45:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:45: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 18:45: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 18:45: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 18:45: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 18:45: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 18:45: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 18:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:45:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:45: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 18:45: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 18:45: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 18:45: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 18:45: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 18:45: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 18:45:11 INFO - Downloading docopt-0.6.1.tar.gz 18:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:45:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:45:11 INFO - Running setup.py install for blobuploader 18:45:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:45:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:45:11 INFO - Running setup.py install for requests 18:45:12 INFO - Running setup.py install for docopt 18:45:12 INFO - Successfully installed blobuploader requests docopt 18:45:12 INFO - Cleaning up... 18:45:12 INFO - Return code: 0 18:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:12 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:45:12 INFO - Using env: {'DISPLAY': ':0', 18:45:12 INFO - 'HOME': '/home/cltbld', 18:45:12 INFO - 'LANG': 'en_US.UTF-8', 18:45:12 INFO - 'LANGUAGE': 'en_US:en', 18:45:12 INFO - 'LOGNAME': 'cltbld', 18:45:12 INFO - 'MAIL': '/var/mail/cltbld', 18:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:12 INFO - 'MOZ_NO_REMOTE': '1', 18:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:12 INFO - 'NO_EM_RESTART': '1', 18:45:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:12 INFO - 'PWD': '/builds/slave/test', 18:45:12 INFO - 'SHELL': '/bin/bash', 18:45:12 INFO - 'SHLVL': '1', 18:45:12 INFO - 'TERM': 'linux', 18:45:12 INFO - 'TMOUT': '86400', 18:45:12 INFO - 'USER': 'cltbld', 18:45:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:12 INFO - '_': '/tools/buildbot/bin/python'} 18:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:12 INFO - Running setup.py (path:/tmp/pip-QLN63P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:12 INFO - Running setup.py (path:/tmp/pip-cEu53Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:12 INFO - Running setup.py (path:/tmp/pip-e2xHjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:12 INFO - Running setup.py (path:/tmp/pip-jXbGyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:12 INFO - Running setup.py (path:/tmp/pip-AzK74P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:12 INFO - Running setup.py (path:/tmp/pip-btfx2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:13 INFO - Running setup.py (path:/tmp/pip-kTNm6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:13 INFO - Running setup.py (path:/tmp/pip-wThZiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:13 INFO - Running setup.py (path:/tmp/pip-X857ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:13 INFO - Running setup.py (path:/tmp/pip-swjxGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:13 INFO - Running setup.py (path:/tmp/pip-WQJTev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:13 INFO - Running setup.py (path:/tmp/pip-j9dR66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:13 INFO - Running setup.py (path:/tmp/pip-GktnZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:13 INFO - Running setup.py (path:/tmp/pip-3IQdtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:13 INFO - Running setup.py (path:/tmp/pip-ycoI2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:13 INFO - Running setup.py (path:/tmp/pip-TpeCJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:13 INFO - Running setup.py (path:/tmp/pip-j_nP_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:45:13 INFO - Running setup.py install for manifestparser 18:45:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:45:13 INFO - Running setup.py install for mozcrash 18:45:13 INFO - Running setup.py install for mozdebug 18:45:14 INFO - Running setup.py install for mozdevice 18:45:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Running setup.py install for mozfile 18:45:14 INFO - Running setup.py install for mozhttpd 18:45:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Running setup.py install for mozinfo 18:45:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Running setup.py install for mozInstall 18:45:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Running setup.py install for mozleak 18:45:14 INFO - Running setup.py install for mozlog 18:45:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Running setup.py install for moznetwork 18:45:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:45:14 INFO - Running setup.py install for mozprocess 18:45:15 INFO - Running setup.py install for mozprofile 18:45:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:45:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:45:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:45:15 INFO - Running setup.py install for mozrunner 18:45:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:45:15 INFO - Running setup.py install for mozscreenshot 18:45:15 INFO - Running setup.py install for moztest 18:45:15 INFO - Running setup.py install for mozversion 18:45:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:45:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:45:15 INFO - Cleaning up... 18:45:15 INFO - Return code: 0 18:45:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:15 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:45:15 INFO - Using env: {'DISPLAY': ':0', 18:45:15 INFO - 'HOME': '/home/cltbld', 18:45:15 INFO - 'LANG': 'en_US.UTF-8', 18:45:15 INFO - 'LANGUAGE': 'en_US:en', 18:45:15 INFO - 'LOGNAME': 'cltbld', 18:45:15 INFO - 'MAIL': '/var/mail/cltbld', 18:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:15 INFO - 'MOZ_NO_REMOTE': '1', 18:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:15 INFO - 'NO_EM_RESTART': '1', 18:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:15 INFO - 'PWD': '/builds/slave/test', 18:45:15 INFO - 'SHELL': '/bin/bash', 18:45:15 INFO - 'SHLVL': '1', 18:45:15 INFO - 'TERM': 'linux', 18:45:15 INFO - 'TMOUT': '86400', 18:45:15 INFO - 'USER': 'cltbld', 18:45:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:15 INFO - '_': '/tools/buildbot/bin/python'} 18:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:15 INFO - Running setup.py (path:/tmp/pip-dkKemc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:15 INFO - Running setup.py (path:/tmp/pip-WDQsWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:15 INFO - Running setup.py (path:/tmp/pip-qdGotH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:16 INFO - Running setup.py (path:/tmp/pip-5mZApH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:16 INFO - Running setup.py (path:/tmp/pip-1a5E7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:16 INFO - Running setup.py (path:/tmp/pip-6Xmjb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:16 INFO - Running setup.py (path:/tmp/pip-I4YuZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:16 INFO - Running setup.py (path:/tmp/pip-sgX0JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:16 INFO - Running setup.py (path:/tmp/pip-CnulS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:16 INFO - Running setup.py (path:/tmp/pip-eLU1QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:16 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)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:16 INFO - Running setup.py (path:/tmp/pip-jBYDEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:16 INFO - Running setup.py (path:/tmp/pip-VEKgPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:16 INFO - Running setup.py (path:/tmp/pip-ufwNw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:16 INFO - Running setup.py (path:/tmp/pip-HJuvfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:16 INFO - Running setup.py (path:/tmp/pip-H2vdE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:16 INFO - Running setup.py (path:/tmp/pip-800B_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:16 INFO - Running setup.py (path:/tmp/pip-0CqnUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:45:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:18 INFO - Downloading blessings-1.6.tar.gz 18:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:45:18 INFO - Installing collected packages: blessings 18:45:18 INFO - Running setup.py install for blessings 18:45:19 INFO - Successfully installed blessings 18:45:19 INFO - Cleaning up... 18:45:19 INFO - Return code: 0 18:45:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:45:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:45:19 INFO - Reading from file tmpfile_stdout 18:45:19 INFO - Current package versions: 18:45:19 INFO - argparse == 1.2.1 18:45:19 INFO - blessings == 1.6 18:45:19 INFO - blobuploader == 1.2.4 18:45:19 INFO - docopt == 0.6.1 18:45:19 INFO - functools32 == 3.2.3-2 18:45:19 INFO - jsonschema == 2.5.1 18:45:19 INFO - manifestparser == 1.1 18:45:19 INFO - mozInstall == 1.12 18:45:19 INFO - mozcrash == 0.17 18:45:19 INFO - mozdebug == 0.1 18:45:19 INFO - mozdevice == 0.48 18:45:19 INFO - mozfile == 1.2 18:45:19 INFO - mozhttpd == 0.7 18:45:19 INFO - mozinfo == 0.9 18:45:19 INFO - mozleak == 0.1 18:45:19 INFO - mozlog == 3.2 18:45:19 INFO - moznetwork == 0.27 18:45:19 INFO - mozprocess == 0.23 18:45:19 INFO - mozprofile == 0.28 18:45:19 INFO - mozrunner == 6.12 18:45:19 INFO - mozscreenshot == 0.1 18:45:19 INFO - mozsystemmonitor == 0.3 18:45:19 INFO - moztest == 0.7 18:45:19 INFO - mozversion == 1.4 18:45:19 INFO - psutil == 3.1.1 18:45:19 INFO - requests == 1.2.3 18:45:19 INFO - wsgiref == 0.1.2 18:45:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:19 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 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:45:19 INFO - Using env: {'DISPLAY': ':0', 18:45:19 INFO - 'HOME': '/home/cltbld', 18:45:19 INFO - 'LANG': 'en_US.UTF-8', 18:45:19 INFO - 'LANGUAGE': 'en_US:en', 18:45:19 INFO - 'LOGNAME': 'cltbld', 18:45:19 INFO - 'MAIL': '/var/mail/cltbld', 18:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:19 INFO - 'MOZ_NO_REMOTE': '1', 18:45:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:19 INFO - 'NO_EM_RESTART': '1', 18:45:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:19 INFO - 'PWD': '/builds/slave/test', 18:45:19 INFO - 'SHELL': '/bin/bash', 18:45:19 INFO - 'SHLVL': '1', 18:45:19 INFO - 'TERM': 'linux', 18:45:19 INFO - 'TMOUT': '86400', 18:45:19 INFO - 'USER': 'cltbld', 18:45:19 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:19 INFO - '_': '/tools/buildbot/bin/python'} 18:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:45:19 INFO - Cleaning up... 18:45:19 INFO - Return code: 0 18:45:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b1c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, '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': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:45: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', 'jsonschema'] in /builds/slave/test/build 18:45: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 jsonschema 18:45:19 INFO - Using env: {'DISPLAY': ':0', 18:45:19 INFO - 'HOME': '/home/cltbld', 18:45:19 INFO - 'LANG': 'en_US.UTF-8', 18:45:19 INFO - 'LANGUAGE': 'en_US:en', 18:45:19 INFO - 'LOGNAME': 'cltbld', 18:45:19 INFO - 'MAIL': '/var/mail/cltbld', 18:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:19 INFO - 'MOZ_NO_REMOTE': '1', 18:45:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:19 INFO - 'NO_EM_RESTART': '1', 18:45:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:19 INFO - 'PWD': '/builds/slave/test', 18:45:19 INFO - 'SHELL': '/bin/bash', 18:45:19 INFO - 'SHLVL': '1', 18:45:19 INFO - 'TERM': 'linux', 18:45:19 INFO - 'TMOUT': '86400', 18:45:19 INFO - 'USER': 'cltbld', 18:45:19 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:19 INFO - '_': '/tools/buildbot/bin/python'} 18:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:45:19 INFO - Cleaning up... 18:45:19 INFO - Return code: 0 18:45:19 INFO - Running post-action listener: _resource_record_post_action 18:45:19 INFO - Running post-action listener: _start_resource_monitoring 18:45:19 INFO - Starting resource monitoring. 18:45:19 INFO - ##### 18:45:19 INFO - ##### Finished create-virtualenv step (success) 18:45:19 INFO - ##### 18:45:19 INFO - ##### 18:45:19 INFO - ##### Running install step. 18:45:19 INFO - ##### 18:45:19 INFO - Running pre-action listener: _resource_record_pre_action 18:45:19 INFO - Running main action method: install 18:45:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:45:20 INFO - Reading from file tmpfile_stdout 18:45:20 INFO - Detecting whether we're running mozinstall >=1.0... 18:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:45:20 INFO - Reading from file tmpfile_stdout 18:45:20 INFO - Output received: 18:45:20 INFO - Usage: mozinstall [options] installer 18:45:20 INFO - Options: 18:45:20 INFO - -h, --help show this help message and exit 18:45:20 INFO - -d DEST, --destination=DEST 18:45:20 INFO - Directory to install application into. [default: 18:45:20 INFO - "/builds/slave/test"] 18:45:20 INFO - --app=APP Application being installed. [default: firefox] 18:45:20 INFO - mkdir: /builds/slave/test/build/application 18:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:45:36 INFO - Reading from file tmpfile_stdout 18:45:36 INFO - Output received: 18:45:36 INFO - /builds/slave/test/build/application/firefox/firefox 18:45:36 INFO - Running post-action listener: _resource_record_post_action 18:45:36 INFO - ##### 18:45:36 INFO - ##### Finished install step (success) 18:45:36 INFO - ##### 18:45:36 INFO - ##### 18:45:36 INFO - ##### Running run-tests step. 18:45:36 INFO - ##### 18:45:36 INFO - Running pre-action listener: _resource_record_pre_action 18:45:36 INFO - Running main action method: run_tests 18:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:45:36 INFO - Python 2.7.3 18:45:36 INFO - Return code: 0 18:45:36 INFO - grabbing minidump binary from tooltool 18:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:36 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 0x2b1a3b0>, '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 0x2b1b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b1b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:45:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:45:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:45:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:45:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:45:36 INFO - Return code: 0 18:45:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:45:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:45:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:45:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:45:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:45:36 INFO - Using env: {'DISPLAY': ':0', 18:45:36 INFO - 'HOME': '/home/cltbld', 18:45:36 INFO - 'LANG': 'en_US.UTF-8', 18:45:36 INFO - 'LANGUAGE': 'en_US:en', 18:45:36 INFO - 'LOGNAME': 'cltbld', 18:45:36 INFO - 'MAIL': '/var/mail/cltbld', 18:45:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:45:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:36 INFO - 'MOZ_NO_REMOTE': '1', 18:45:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:45:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:36 INFO - 'NO_EM_RESTART': '1', 18:45:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:36 INFO - 'PWD': '/builds/slave/test', 18:45:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:45:36 INFO - 'SHELL': '/bin/bash', 18:45:36 INFO - 'SHLVL': '1', 18:45:36 INFO - 'TERM': 'linux', 18:45:36 INFO - 'TMOUT': '86400', 18:45:36 INFO - 'USER': 'cltbld', 18:45:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822', 18:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:36 INFO - '_': '/tools/buildbot/bin/python'} 18:45:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:45:36 INFO - mozversion application_buildid: 20160715170951 18:45:36 INFO - mozversion application_changeset: 1b3826196e7efc0c7442a02207ef833003de2dfe 18:45:36 INFO - mozversion application_display_name: Nightly 18:45:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:45:36 INFO - mozversion application_name: Firefox 18:45:36 INFO - mozversion application_remotingname: firefox 18:45:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:45:36 INFO - mozversion application_vendor: Mozilla 18:45:36 INFO - mozversion application_version: 50.0a1 18:45:36 INFO - mozversion platform_buildid: 20160715170951 18:45:36 INFO - mozversion platform_changeset: 1b3826196e7efc0c7442a02207ef833003de2dfe 18:45:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:45:36 INFO - mozversion platform_version: 50.0a1 18:45:36 INFO - using testdate: 1468633536 18:45:36 INFO - actual date: 1468633536 18:45:36 INFO - starting webserver on 'localhost:37112' 18:45:36 INFO - SUITE-START | Running 2 tests 18:45:36 INFO - TEST-START | dromaeo_css 18:45:36 INFO - operating with platform_type : linux_ 18:45:36 INFO - Initialising browser for dromaeo_css test... 18:45:36 INFO - TEST-INFO | started process 4932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLYjYaT/profile http://localhost:37112/getInfo.html) 18:45:41 INFO - PROCESS | 4932 | __metrics Screen width/height:1600/1200 18:45:41 INFO - PROCESS | 4932 | colorDepth:24 18:45:41 INFO - PROCESS | 4932 | Browser inner width/height: 1024/697 18:45:41 INFO - PROCESS | 4932 | __metrics 18:45:42 INFO - PROCESS | 4932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:45:42 INFO - PROCESS | 4932 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:45:42 INFO - PROCESS | 4932 | console.error: PushService: 18:45:42 INFO - PROCESS | 4932 | stateChangeProcessEnqueue: Error transitioning state 18:45:42 INFO - PROCESS | 4932 | UnknownError 18:45:43 INFO - TEST-INFO | 4932: exit 0 18:45:43 INFO - Browser initialized. 18:45:43 INFO - Running cycle 1/1 for dromaeo_css test... 18:45:43 INFO - TEST-INFO | started process 5015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLYjYaT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:55 INFO - PROCESS | 5015 | RSS: Main: 159326208 18:45:55 INFO - PROCESS | 5015 | 18:46:46 INFO - PROCESS | 5015 | 1468633606185 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:48:58 INFO - PROCESS | 5015 | Cycle 1(1): loaded http://localhost:37112/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37112/tests/dromaeo/cssquery-ext.html) 18:49:00 INFO - PROCESS | 5015 | RSS: Main: 242040832 18:49:00 INFO - PROCESS | 5015 | 18:52:02 INFO - PROCESS | 5015 | Cycle 1(1): loaded http://localhost:37112/tests/dromaeo/cssquery-ext.html (next: http://localhost:37112/tests/dromaeo/cssquery-jquery.html) 18:52:03 INFO - PROCESS | 5015 | RSS: Main: 250421248 18:52:03 INFO - PROCESS | 5015 | 18:55:05 INFO - PROCESS | 5015 | Cycle 1(1): loaded http://localhost:37112/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37112/tests/dromaeo/cssquery-mootools.html) 18:55:06 INFO - PROCESS | 5015 | RSS: Main: 242978816 18:55:06 INFO - PROCESS | 5015 | 18:58:08 INFO - PROCESS | 5015 | Cycle 1(1): loaded http://localhost:37112/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37112/tests/dromaeo/cssquery-prototype.html) 18:58:09 INFO - PROCESS | 5015 | RSS: Main: 243453952 18:58:09 INFO - PROCESS | 5015 | 19:01:12 INFO - PROCESS | 5015 | Cycle 1(1): loaded http://localhost:37112/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37112/tests/dromaeo/cssquery-yui.html) 19:01:12 INFO - PROCESS | 5015 | RSS: Main: 217919488 19:01:12 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | Cycle 1(1): loaded http://localhost:37112/tests/dromaeo/cssquery-yui.html (next: http://localhost:37112/tests/dromaeo/cssquery-dojo.html) 19:04:15 INFO - PROCESS | 5015 | RSS: Main: 273928192 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | __start_tp_report 19:04:15 INFO - PROCESS | 5015 | _x_x_mozilla_page_load 19:04:15 INFO - PROCESS | 5015 | _x_x_mozilla_page_load_details 19:04:15 INFO - PROCESS | 5015 | |i|pagename|runs| 19:04:15 INFO - PROCESS | 5015 | |0;dojo.html;2638.287662331171;2652.151311580295;2681.95977060344;2690.3274181454635;2692.8384296942186;4723.149833030053;4741.549552792483;4786.521347865212;4817.8795530111765;4876.512348765126;4811;4819.807207711696;4821.590164835984;4840.7579621018895;4853.5389138031915;4354.477462704472;4411.448568928884;4422.645934892699;4483.708558943675;4541.863744087683;4453.131639330332;4472.3515090311885;4500.639948804088;4505.481869585007;4533.727976321431;4295.935560966588;4316.611504964569;4318.244307246251;4331.740095594275;4352.91294174118;4328.61042506176;4346.56534346566;4380.299152135675;4395.296752519582;4415.359772832921;4390.165868484977;4400.955990440087;4404.845830395952;4419.40338054365;4421.6904816662845;4695.389599352096;4716.103940251375;4739.644526660514;4772.141014617368;4804.711717296938;5117.974410127963;5134;5144.099782538041;5146.099432599281;5156.742162891878;5103.081445339837;5113.923291150636;5124.4875512448825;5137.717425541594;5192.55863251624;4804.279358096313;4859.24681674334;4869.245266983623;4878.780454879539;4886.364772579542;5336.839894803162;5339.385970613429;5349.893002139936;5350.010248104113;5352.357717073976;4888.780004899717;4931.112399768004;4963.6525443218625;4976.278439626234;5014.247862820606;5116.897662046738;5157.045946499909;5188.0661480933795;5202.323697919246;5213.452587478336;5086.160783470686;5102.158143906214;5104;5105.310783044316;5138.075146473596;4877.926831097537;5038.899222015539;5061.620378471629;5063.316452278968;5076.898462030738;4331.45856767904;4335.089631177488;4354.542773008852;4381.9342709859375;4382.145481631052;3789.621037896188;3803.486529318562;3807.276617442677;3839.6352346527037;3845.115623406632;4284.421603083606;4335.631471324973;4413.823447062082;4448.35498852671;4461.308497182942;5170.896582068338;5193.532582067632;5194.844154675366;5199.1161502544655;5239.921401178985;4462.241418958784;4467.218236808546;4469.597736203692;4505.053938672915;4517.909641807146;4435.290353543418;4481.910361792811;4545.704529205591;4593;4624.121416930772;4847.951520484891;4870.878228044327;4875.68308059975;4909.656324057281;4923.753812309442;5132.666376685504;5160.664556803796;5165.096108180978;5169.457206993212;5171.198464238049;4760.952390476189;4831.130396528518;4855.6115510758345;4884.535969082932;4928.162212423825;5043.646944713835;5051.267566202953;5056.82301119459;5060.569851562658;5113.079645663816;5147.845564633067;5155.639105262596;5157.07172708916;5160.948390516047;5161.354830646169;4903.41159060915;4930.038642464685;4941.777620007037;4947.851564453073;4968.22992436173;4942.382202224722;4974.626902982219;4975;4975.651704380659;5018.5232402921665;4790.496997815179;4889.266610008527;4895.583875370491;4895.657303988686;4954;4907.509249075064;5053.191489361684;5073.264376665436;5095.770690319018;5098.388193416814;5001.8499445016705;5006.4743201963265;5007.574356179765;5007.924881126859;5058.038972595195 19:04:15 INFO - PROCESS | 5015 | |1;ext.html;563.9774409023637;569.7236840132535;581.3430823169817;590.3771521045295;592.9110633404991;26402.075927342546;37740.43389349159;39125;39166.21667566647;43705.34441983373;40165;40576;40619.390709139385;40630.7968460158;40811;7524.811879703024;7681.3086822186;7860.096088949758;7863.174366691501;7866.7639970800965;7466.664000120008;7666.425018123635;7726.884096738553;9865;10160.796784064314;9470.66852660157;9489.240860731159;9524.380915240487;9561.99599042101;9578.042195780436;10166.4916754162;10181.745456363577;10183.59265629374;10191.847122293171;10237.078662920372;7605.353544948686;9758.512074396253;10124.34191777532;10165.085142337226;10452.425116618582;38799.224015519816;39017;39060.60939390598;39246.607533924864;39347.40978885319;2543.910963116282;2814.6059551662784;3105.3634005028916;3212.3254116635535;3421.538092357525;21754;29437.70562294371;31245.37509249825;31582.526262106087;32233.83883080593;3104.937901241985;3122.687731226882;3158.078081066963;3176.0630613968933;3184.9044528664176;257476;258007;259080;259537;259561;52235.73882130641;52893.73553132287;52978.735106324995;53174.468255317726;53236;43629;44423.55576444194;44475.55524444779;44546.77726611346;48288.7585562077;14069.788953165711;14250.073745206528;14279.286035698382;14313.499027533986;14326.713465730836;9080.137386948229;10300.896991030144;10354.844677329846;10374.948125259325;10380.584776609003;15269.923650381677;15606;15781.605459863365;15927.442539511294;15985.760213596805;6161.630302181883;6255.435271538739;6298.685065746694;6299.181106456132;6323.936760632427;834.4909605140859;918.8070505193984;977.9511024448749;995.4375777685584;1040.9167266618804;28182.29544261369;28265.72804223815;28643.2839179018;28675.56986645202;28872.711272887;955.1690029674228;976.7167521418709;980.9754756131153;984.8719666443317;994.6667866264737;14804.481843135654;15258.237088145548;15263.236838158047;15536.378544858306;15558.84441155574;357472;358306;361355;362493;362780;48724.15310162732;48822.511774881794;49012;49059;49064.50935490671;1062.293574772783;1154.2324354304294;1211.0432758121162;1218.2020776391478;1228.91397602167;27783.58324625173;29139.417211655225;29170.416591667625;29468.852655736584;29482.410351793274;41369.1726165469;41506.58493415148;41507.37738933858;41550.58449415588;41885.79057104817;244646;248670;248712;249242;249281;30466.543001854545;31419.528707068956;31618;31633;34354.48468272928;32583.837080814443;33302.66697332996;38541.6145838538;39205.41191882181;39361.80319098386;29978.100656980325;30453;30613.23466913345;30617.23456913506;31453;33507;34279.82860085684;34420;34538.82730586331;34593.65406345904;32958;33852.153696157795;34045.14887127842;34158.65841341555;34215.48676769801;30121.246968825955;31100.46894134705;31199.84400077985;31783.682163178073;35120.64879351276;25185.370365741004;25225.369365766004;25467.745322546536;25617.231483055537;25812.22563323103 19:04:15 INFO - PROCESS | 5015 | |2;jquery.html;364.12428110394495;375.962403759624;376.78523241752197;386.42422790042843;386.6269050366396;16380.91809540952;17673.29306827727;17919.910400447978;18591.442256732287;18605.441836744903;17243.396481123164;17590.912045439756;17904.641907161847;17921.731174032368;18258.81741182588;5154.149565321717;5346.599005074616;5373.81191658292;5392.164214546732;5396.811111611094;3707.277080969212;5320.707361095138;6001.699915004254;6319.74721011159;6333.524985626073;6106.877862442749;6348.0477928310665;6356.68216589171;6378.532937424372;6381.521385896053;6205;6232.968835155841;6236.501079913596;6269.0909818076125;6277.623342599436;3449.3963556377785;3615.349237137314;3616.2044350242904;3667.9266414671824;5197.636165468419;20616.175352986014;21099.78900211009;21866.781332186634;21903.233386831544;22166.889165554232;2042.4791678122133;2043.2644248070683;2069.8344132469365;2309.3302942146925;2359.882005899698;13092.083554151213;13596.59210223695;13755.931220343935;14052;14179.361928713233;1670.5072003758921;1787.2046939112101;1806.4761219246402;1830.8718389712726;1837.0355563329226;66752.3324766751;68999;69151.3084869155;69479;69494.65252673755;9750.951245243728;10037.397756134655;10113.14038306752;10129.696109116738;10391.428471434143;13475.124116932368;13586.320683965761;13604.93197534006;13639.181649100887;13659;7330.706771729071;7363.8159046024275;7746.535207887545;7820.68717251315;7840.255175758294;7556.357709594636;7559.1684914659345;7563.9621801890635;7671.923280767121;7678.424118191158;6183.288921773964;6191.535634827406;6250.624962502265;6324.462420694201;6440.808450436684;1659.1787065402461;1683.4360489236244;1761.207456644515;1791.3192986664983;1846.6768315544725;721.8664547058811;755.112742527528;791.525084949026;839.4459656626597;854.0306751836586;16337.18314084291;16854.662906741796;16979.320827166775;17230.65538689219;17320.566985825455;751.7331347371604;777.859985202669;780.3328154427883;835.6949713354581;859.6690274244336;9220.8616870747;9296;9472.289578281656;9576.186024188022;9674.177694895872;366186;366446.85201757617;370392;380464;385326;67389.6630516854;69941.30058699349;70201.64899175472;70361.64819175974;70464;894.5706061090713;903.8779764731677;907.5462268865567;913.7167478081816;928.0672923711627;15824.287907043981;15895.205239737736;16011.839881601501;16108;16144.596385090466;35610.64389356177;36041;36339;36481.27037459289;36693;46460.7676961613;47989.5201047999;48730.75634621804;48765;50115.49884501254;7589.924100758922;7700.537967721954;7723.9227607723205;7941.086775020715;8028.6387112578905;12054.638360849189;12111.636650900487;12344.567940122053;12552.309622970459;12680.492780288685;10433.113185379329;10506.474676266309;10571.577136914744;10833.67498975032;11108.166887483309;4816.831410900601;6477.578957367945;10882.388226825664;10935.507902144263;10987.39569323695;10799.244052916534;11111;11122.165837562377;11141.331520108819;11286.774264514499;6621.370969757865;7039.1553013638695;7283.053203083778;7374.557526548424;7995.200479952191;7772.067351917806;10203.693889183336;10819.404932728776;10882.945585272022;11037 19:04:15 INFO - PROCESS | 5015 | |3;mootools.html;1190.2382475215863;1201.321253491777;1319.6041187643707;1399.6920677450958;1410.7037522120347;6990.196127445332;7192.748253811118;7682.6158692065455;8155.6329965151635;8228.218319259668;7668.578228197447;7914.999999999985;7927.8414431711335;8090.91909080909;8119.26926576608;2222.6888235646984;2249.3589327041796;2250.437390652337;2251.3583628665833;2252.200468833565;1863.7763468383812;2038.8572799904014;2085.7601375841746;2088.56140210556;2210.403191138392;2219.7891200335944;2241.529278851443;2245.932622021342;2247.9887600561974;2248.2580748353002;2142.1752625239237;2207.768184340637;2211.94470138247;2212.668099785029;2217.5675743230076;2146.205903815598;2183.4213933307688;2186.30038387716;2235.0947866114248;2236.966445503319;8162.510249385056;8213.260806527382;8237.382196335298;8282;8286.171382861727;4585.12882552314;4718.669693121483;4720.772599124252;4725.456572494173;4728.598069164125;2764.903228386997;2780.527310357244;2887.5524293734507;2890.0462847260355;2895.3051267695814;784.9411294152961;813.3005615171007;827.2472050434076;827.4745536584252;828.668532586964;10883.23817332787;10997.12023038163;11008.724781880357;11028.393438360887;11039;3960.980195099006;4108.979455102705;4145.0880806222585;4211.073563815956;4214.4099826024385;8596.398252122417;8654.264387527004;8655.6537738491;8661.956690216508;8667.653293868174;4563.817447302137;4565.429321334833;4566.429196350457;4567.9086418271445;4570.634349252052;3572.9464058039152;4207.810648520824;4225.514065882463;4231.640310573574;4233.216854881856;3080.753539716817;3206.150370151912;3598.2803439312243;3602.369585322558;3647.6534729200685;1043.3270540501485;1049.2655141401049;1051.3008849115406;1080.1412876762993;1080.7460246841986;761.208343322949;770.7726220764888;777.3897490469953;792.4690457393444;797.6171437709816;4739.573438390561;4893.437254715682;4898.363212782387;4971.1549036663855;5015.272785446163;835.5613303015965;855.4140413816556;868.847951608466;875.4397185801092;878.1745159550003;3181.4750566156326;3188.58548388708;3299.5050742388353;3319.7676162668863;3370.5449764281507;9794.265430092772;10042.447665378328;10050.447225402528;10150.593976241016;10159.4920253987;10058.698239052841;10074.496275186211;10081.143102836195;10081.546330415149;10097;7546.698132074765;7628.54228746277;7633.541987480658;7636.465447418627;7640.656170472342;5056.191009438472;5175.223716442413;5343.251944727819;5366.865828354323;5482.259894914295;9012.369134160546;9313.767155821159;9371.906280937103;9501.477418742035;9519.952400237955;9422.387544809566;9619.615215391306;9623.518824058909;9639.469829159463;9643.614255429704;6176.4441200292185;6724.327567243432;6884.586924784529;6885.758998435031;7086.185088714864;7836.37309015266;7838.3337416320255;7860.5676687782725;7902;7916.366690664848;6855.931440685529;6968.198657154492;7144.3212894774915;7163.283671633004;7257.419406447366;6905.482088843256;6996.475264355092;7023.824404389931;7044.049053377728;7111.43108551325;6230.376962303733;6464.838379040561;6467.932791089417;6607.603543787388;6630.3038180992235;4453.287474004143;4490.640748740027;4550.180967425763;4606.585407313358;4629.4676112247525;4464.084862603094;4527.886802829824;4566.292224705043;4592.770361481979;4653.976730116327 19:04:15 INFO - PROCESS | 5015 | |4;prototype.html;305.7462306285783;306.9324748555317;307.17982985428904;308.4524968181479;310.86477382338677;4847.515248475151;4848.927266091011;4859.416869975604;4868.367112275404;4940.876478088049;4820.373351464314;4854.5679024687715;4860.343853579766;4860.6597538172255;4864.440589332228;3575.964240357596;3689.501917241165;3747.1381582236104;3818.217265460588;3837.769733815966;3789.7726136431766;3810.8856734298015;3811.2377524495073;3814.5422549293985;3823.9235215295676;3763.7929913854728;3766.905827354324;3775.6413140751583;3797.854212370016;3798.9050273743237;3563.1626567756557;3670.596234414226;3809.733318667694;3824.235152969389;3845.6731177849915;3387.7120444762227;3392.915177120567;3397;3404.7616666833333;3405.863765449379;4796.952030479685;4798.424189097295;4803.231482962709;4806.807727690888;4821.68659037165;4249.893752656209;4374.168907907488;4398.868033958986;4404.669649776248;4432.15789000089;4996.925046124311;5038.143515602356;5039.269305950616;5043.142665746832;5080.466551012164;3716.4425336199515;3720.441933709938;3722.274156539476;3732.328180927432;3734.271816995687;5104.157813960692;5347.411784703719;5355.7857685692525;5359.169328754049;5359.437259087778;3772.86794962175;3785.848566057382;3791.6208379161862;3793.943090853639;3809.6760975804737;3833.5783063862955;3838.1939792643857;3840.4431357453013;3849.268638958589;3853.845846166122;4352.173087113439;4352.69531132824;4354.6951713380395;4367.2575662137515;4368.868933931987;4805.255185446262;5027.84916452507;5049.82325618609;5050.924236136461;5104.668196567211;1282.6793301674581;1296.112163168214;1302.8306320178506;1306.1053178572708;1315.7171208190284;813.7762115418188;833.4124442268186;837.4431003382682;934.9392289501296;945.6548359848744;4400.09797991411;4440.156370289635;4441.755703436277;4477.1269602427865;4489.820407183676;5180.4560521145495;5191.195364718475;5208.479152084837;5230.3985041719925;5246.134387825966;4329.203380597017;4348.34774783785;4455.799489023002;4469.033625204965;4471.396361491222;2745.9038933637225;2746.615473833665;2764.3227409284855;2773.5839624056553;2776.236534952904;4882.218844984785;4885.242787367893;4909.386326709161;4913.263010548375;4920.163572192736;5060.797568097235;5250.789968401221;5296.761645725952;5313.707746073926;5393.541548968303;4966.478519755374;4967.279744437036;4973.651844370932;4974.37820272466;4978.6017118630425;5247.9737601311745;5253.448387919367;5268;5276.604254680838;5287.524122828964;4869.342638743724;5147.742612869416;5152.871178220575;5170.224466330081;5182.922256166236;4963.4540200577185;4968.900621987467;4970.776315065903;4971.37857767779;4988.12707776145;3732.749528907846;3744.64425879541;3749.887503374903;3759.755615884959;3762.4168253920684;5105.7702403391195;5231.267622532772;5311.362636483646;5312.867178320418;5332.706701131475;5056.469070747666;5085.821996230115;5087.481696560227;5091.9490805092955;5101.642884998015;5071.137906555969;5083.29709547291;5091.974540127424;5093.949060509348;5114.5652619527755;4803.855884323476;5022.74886255693;5026.824061157842;5053.974730126326;5054.570361519165;4892.241702536113;4970.801167953241;5137.8715532112;5143.742812859267;5149.918517111299;4999.925001125059;5022.246663000433;5023.899522009467;5027.461132128252;5032.899342013213 19:04:15 INFO - PROCESS | 5015 | |5;yui.html;655.573876979963;659.8977158540428;660.7293895218666;696.348913765629;696.4498046543231;12819.743605127891;13664.521741739016;13727.313634318269;13759.312034398277;13759.79360309596;12335.383230838466;12969.80545291819;13156.210627362365;14516.927415362934;14529.273536323195;774.3263360876045;792.6987744657022;798.1579433697447;799.4643588795492;799.6441583495347;793.0681449297082;795.4988357334871;795.9442839001243;796.0288448093316;796.5419883566943;839.5508403004394;840.504102579478;841.3395484544634;841.6212704283066;841.8316336732647;828.2131974624101;831.6881169561415;832.01406333495;833.1918039501674;833.8624127019035;608.5618354784548;608.5831205624118;609.704293417692;610.4170517156106;610.8442347201435;12252.081093917903;12265.141440099196;12279;12283.815742763865;12284.447199876027;12495;12550.435230414654;12754.68113297175;13065.608031759062;13379.86620133796;9927.610134581164;10133.391996480235;10187;10474.58101675932;10508.316959397614;3301.057649337117;3309.1561651778884;3309.3712194682935;3350.1122202616325;3392.7794693344854;40010.79994600038;56979.43020569824;57031.71484142594;61444.38555614431;62634.37365626331;9830.803383932282;9972.850407243897;10152.137068349273;10286.268321518708;10359.119474844723;19231.326903558307;19294.807051929583;19298.4210473686;19616;19642.11610477532;6497.512686548528;6608.7026083825385;6640.800775976729;6779.35596118368;6894.413974812097;3559.18138828071;3636.2363903580545;3680.1351682354625;3687.170386663033;3703.703703703751;2744.6157537944705;2835.929101772431;2844.928876778056;2845.132234668446;2845.445138198073;492.6034542193569;495.50202046942593;502.5276240334147;502.5376653478808;502.6230327254559;529.5895680847374;558.7988324203284;561.5647872898454;572.8882867840814;586.1617886422372;7546.20764819697;7599.240075992467;7625.199354067854;7865.567393793396;8387.454815436977;475.23961661341576;561.5591760468013;562.8986782379129;571.6684323092679;573.5698226330252;3689.612590677994;4000.8199631016673;4011.5787842276723;4096.979515102465;4121.752694838319;90362;90505.54747226222;92555;93718.0628193723;94047;49104.50895491071;66786;66856.33143668598;79817;83059;44312.77843610761;72290;73505.63247183837;74825.62587187029;76030;8011.559364235023;9021.097890210927;9152.771180720536;9218.400803947721;9254.12085851843;30947.69052309448;40534.986625334604;42348.5765142357;43364.56635433605;43461;39319.41020884632;40663;41377.37933930933;45984.770076149405;59598.10602840874;11085.22403431752;11138.498767555317;11175.273607215506;11423.885761142283;11455.828162577734;12140.339698532332;13321.33393330349;13560.389782459615;13626;13731.588052358446;11783.823242651539;11974.101942354184;12011.099167562643;12348.691282718004;12521.686957825763;12028.63914082579;12433.813492797795;12724.300163491096;12809.23144611289;13628.182309061489;11873;11973.281603103842;12105.515779368727;12622.305773182563;12629.431675574804;10626.787464250827;10665.573377064831;10793.136549076213;10930.617428389969;11243.718907027063;9749.220062395134;9818.410895346302;9892.554835032297;10016.899831001596;10476.109530689671 19:04:15 INFO - PROCESS | 5015 | __end_tp_report 19:04:15 INFO - PROCESS | 5015 | __start_cc_report 19:04:15 INFO - PROCESS | 5015 | _x_x_mozilla_cycle_collect,1084 19:04:15 INFO - PROCESS | 5015 | __end_cc_report 19:04:15 INFO - PROCESS | 5015 | __startTimestamp1468634655786__endTimestamp 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | ------- Summary: start ------- 19:04:15 INFO - PROCESS | 5015 | Number of tests: 6 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | [#0] dojo.html Cycles:165 Average:4744.85 Median:4884.54 stddev:502.12 (10.3%) stddev-sans-first:475.69 19:04:15 INFO - PROCESS | 5015 | Values: 2638.3 2652.2 2682.0 2690.3 2692.8 4723.1 4741.5 4786.5 4817.9 4876.5 4811.0 4819.8 4821.6 4840.8 4853.5 4354.5 4411.4 4422.6 4483.7 4541.9 4453.1 4472.4 4500.6 4505.5 4533.7 4295.9 4316.6 4318.2 4331.7 4352.9 4328.6 4346.6 4380.3 4395.3 4415.4 4390.2 4401.0 4404.8 4419.4 4421.7 4695.4 4716.1 4739.6 4772.1 4804.7 5118.0 5134.0 5144.1 5146.1 5156.7 5103.1 5113.9 5124.5 5137.7 5192.6 4804.3 4859.2 4869.2 4878.8 4886.4 5336.8 5339.4 5349.9 5350.0 5352.4 4888.8 4931.1 4963.7 4976.3 5014.2 5116.9 5157.0 5188.1 5202.3 5213.5 5086.2 5102.2 5104.0 5105.3 5138.1 4877.9 5038.9 5061.6 5063.3 5076.9 4331.5 4335.1 4354.5 4381.9 4382.1 3789.6 3803.5 3807.3 3839.6 3845.1 4284.4 4335.6 4413.8 4448.4 4461.3 5170.9 5193.5 5194.8 5199.1 5239.9 4462.2 4467.2 4469.6 4505.1 4517.9 4435.3 4481.9 4545.7 4593.0 4624.1 4848.0 4870.9 4875.7 4909.7 4923.8 5132.7 5160.7 5165.1 5169.5 5171.2 4761.0 4831.1 4855.6 4884.5 4928.2 5043.6 5051.3 5056.8 5060.6 5113.1 5147.8 5155.6 5157.1 5160.9 5161.4 4903.4 4930.0 4941.8 4947.9 4968.2 4942.4 4974.6 4975.0 4975.7 5018.5 4790.5 4889.3 4895.6 4895.7 4954.0 4907.5 5053.2 5073.3 5095.8 5098.4 5001.8 5006.5 5007.6 5007.9 5058.0 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | [#1] ext.html Cycles:180 Average:44477.04 Median:33679.58 stddev:76931.28 (228.4%) stddev-sans-first:77076.44 19:04:15 INFO - PROCESS | 5015 | Values: 564.0 569.7 581.3 590.4 592.9 26402.1 37740.4 39125.0 39166.2 43705.3 40165.0 40576.0 40619.4 40630.8 40811.0 7524.8 7681.3 7860.1 7863.2 7866.8 7466.7 7666.4 7726.9 9865.0 10160.8 9470.7 9489.2 9524.4 9562.0 9578.0 10166.5 10181.7 10183.6 10191.8 10237.1 7605.4 9758.5 10124.3 10165.1 10452.4 38799.2 39017.0 39060.6 39246.6 39347.4 2543.9 2814.6 3105.4 3212.3 3421.5 21754.0 29437.7 31245.4 31582.5 32233.8 3104.9 3122.7 3158.1 3176.1 3184.9 257476.0 258007.0 259080.0 259537.0 259561.0 52235.7 52893.7 52978.7 53174.5 53236.0 43629.0 44423.6 44475.6 44546.8 48288.8 14069.8 14250.1 14279.3 14313.5 14326.7 9080.1 10300.9 10354.8 10374.9 10380.6 15269.9 15606.0 15781.6 15927.4 15985.8 6161.6 6255.4 6298.7 6299.2 6323.9 834.5 918.8 978.0 995.4 1040.9 28182.3 28265.7 28643.3 28675.6 28872.7 955.2 976.7 981.0 984.9 994.7 14804.5 15258.2 15263.2 15536.4 15558.8 357472.0 358306.0 361355.0 362493.0 362780.0 48724.2 48822.5 49012.0 49059.0 49064.5 1062.3 1154.2 1211.0 1218.2 1228.9 27783.6 29139.4 29170.4 29468.9 29482.4 41369.2 41506.6 41507.4 41550.6 41885.8 244646.0 248670.0 248712.0 249242.0 249281.0 30466.5 31419.5 31618.0 31633.0 34354.5 32583.8 33302.7 38541.6 39205.4 39361.8 29978.1 30453.0 30613.2 30617.2 31453.0 33507.0 34279.8 34420.0 34538.8 34593.7 32958.0 33852.2 34045.1 34158.7 34215.5 30121.2 31100.5 31199.8 31783.7 35120.6 25185.4 25225.4 25467.7 25617.2 25812.2 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | [#2] jquery.html Cycles:180 Average:23871.72 Median:376.37 stddev:61569.69 (16358.7%) stddev-sans-first:61717.11 19:04:15 INFO - PROCESS | 5015 | Values: 364.1 376.0 376.8 386.4 386.6 16380.9 17673.3 17919.9 18591.4 18605.4 17243.4 17590.9 17904.6 17921.7 18258.8 5154.1 5346.6 5373.8 5392.2 5396.8 3707.3 5320.7 6001.7 6319.7 6333.5 6106.9 6348.0 6356.7 6378.5 6381.5 6205.0 6233.0 6236.5 6269.1 6277.6 3449.4 3615.3 3616.2 3667.9 5197.6 20616.2 21099.8 21866.8 21903.2 22166.9 2042.5 2043.3 2069.8 2309.3 2359.9 13092.1 13596.6 13755.9 14052.0 14179.4 1670.5 1787.2 1806.5 1830.9 1837.0 66752.3 68999.0 69151.3 69479.0 69494.7 9751.0 10037.4 10113.1 10129.7 10391.4 13475.1 13586.3 13604.9 13639.2 13659.0 7330.7 7363.8 7746.5 7820.7 7840.3 7556.4 7559.2 7564.0 7671.9 7678.4 6183.3 6191.5 6250.6 6324.5 6440.8 1659.2 1683.4 1761.2 1791.3 1846.7 721.9 755.1 791.5 839.4 854.0 16337.2 16854.7 16979.3 17230.7 17320.6 751.7 777.9 780.3 835.7 859.7 9220.9 9296.0 9472.3 9576.2 9674.2 366186.0 366446.9 370392.0 380464.0 385326.0 67389.7 69941.3 70201.6 70361.6 70464.0 894.6 903.9 907.5 913.7 928.1 15824.3 15895.2 16011.8 16108.0 16144.6 35610.6 36041.0 36339.0 36481.3 36693.0 46460.8 47989.5 48730.8 48765.0 50115.5 7589.9 7700.5 7723.9 7941.1 8028.6 12054.6 12111.6 12344.6 12552.3 12680.5 10433.1 10506.5 10571.6 10833.7 11108.2 4816.8 6477.6 10882.4 10935.5 10987.4 10799.2 11111.0 11122.2 11141.3 11286.8 6621.4 7039.2 7283.1 7374.6 7995.2 7772.1 10203.7 10819.4 10882.9 11037.0 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | [#3] mootools.html Cycles:180 Average:5206.13 Median:4719.72 stddev:3026.90 (64.1%) stddev-sans-first:3020.35 19:04:15 INFO - PROCESS | 5015 | Values: 1190.2 1201.3 1319.6 1399.7 1410.7 6990.2 7192.7 7682.6 8155.6 8228.2 7668.6 7915.0 7927.8 8090.9 8119.3 2222.7 2249.4 2250.4 2251.4 2252.2 1863.8 2038.9 2085.8 2088.6 2210.4 2219.8 2241.5 2245.9 2248.0 2248.3 2142.2 2207.8 2211.9 2212.7 2217.6 2146.2 2183.4 2186.3 2235.1 2237.0 8162.5 8213.3 8237.4 8282.0 8286.2 4585.1 4718.7 4720.8 4725.5 4728.6 2764.9 2780.5 2887.6 2890.0 2895.3 784.9 813.3 827.2 827.5 828.7 10883.2 10997.1 11008.7 11028.4 11039.0 3961.0 4109.0 4145.1 4211.1 4214.4 8596.4 8654.3 8655.7 8662.0 8667.7 4563.8 4565.4 4566.4 4567.9 4570.6 3572.9 4207.8 4225.5 4231.6 4233.2 3080.8 3206.2 3598.3 3602.4 3647.7 1043.3 1049.3 1051.3 1080.1 1080.7 761.2 770.8 777.4 792.5 797.6 4739.6 4893.4 4898.4 4971.2 5015.3 835.6 855.4 868.8 875.4 878.2 3181.5 3188.6 3299.5 3319.8 3370.5 9794.3 10042.4 10050.4 10150.6 10159.5 10058.7 10074.5 10081.1 10081.5 10097.0 7546.7 7628.5 7633.5 7636.5 7640.7 5056.2 5175.2 5343.3 5366.9 5482.3 9012.4 9313.8 9371.9 9501.5 9520.0 9422.4 9619.6 9623.5 9639.5 9643.6 6176.4 6724.3 6884.6 6885.8 7086.2 7836.4 7838.3 7860.6 7902.0 7916.4 6855.9 6968.2 7144.3 7163.3 7257.4 6905.5 6996.5 7023.8 7044.0 7111.4 6230.4 6464.8 6467.9 6607.6 6630.3 4453.3 4490.6 4550.2 4606.6 4629.5 4464.1 4527.9 4566.3 4592.8 4654.0 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | [#4] prototype.html Cycles:180 Average:4235.96 Median:4856.99 stddev:1221.32 (25.1%) stddev-sans-first:1188.59 19:04:15 INFO - PROCESS | 5015 | Values: 305.7 306.9 307.2 308.5 310.9 4847.5 4848.9 4859.4 4868.4 4940.9 4820.4 4854.6 4860.3 4860.7 4864.4 3576.0 3689.5 3747.1 3818.2 3837.8 3789.8 3810.9 3811.2 3814.5 3823.9 3763.8 3766.9 3775.6 3797.9 3798.9 3563.2 3670.6 3809.7 3824.2 3845.7 3387.7 3392.9 3397.0 3404.8 3405.9 4797.0 4798.4 4803.2 4806.8 4821.7 4249.9 4374.2 4398.9 4404.7 4432.2 4996.9 5038.1 5039.3 5043.1 5080.5 3716.4 3720.4 3722.3 3732.3 3734.3 5104.2 5347.4 5355.8 5359.2 5359.4 3772.9 3785.8 3791.6 3793.9 3809.7 3833.6 3838.2 3840.4 3849.3 3853.8 4352.2 4352.7 4354.7 4367.3 4368.9 4805.3 5027.8 5049.8 5050.9 5104.7 1282.7 1296.1 1302.8 1306.1 1315.7 813.8 833.4 837.4 934.9 945.7 4400.1 4440.2 4441.8 4477.1 4489.8 5180.5 5191.2 5208.5 5230.4 5246.1 4329.2 4348.3 4455.8 4469.0 4471.4 2745.9 2746.6 2764.3 2773.6 2776.2 4882.2 4885.2 4909.4 4913.3 4920.2 5060.8 5250.8 5296.8 5313.7 5393.5 4966.5 4967.3 4973.7 4974.4 4978.6 5248.0 5253.4 5268.0 5276.6 5287.5 4869.3 5147.7 5152.9 5170.2 5182.9 4963.5 4968.9 4970.8 4971.4 4988.1 3732.7 3744.6 3749.9 3759.8 3762.4 5105.8 5231.3 5311.4 5312.9 5332.7 5056.5 5085.8 5087.5 5091.9 5101.6 5071.1 5083.3 5092.0 5093.9 5114.6 4803.9 5022.7 5026.8 5054.0 5054.6 4892.2 4970.8 5137.9 5143.7 5149.9 4999.9 5022.2 5023.9 5027.5 5032.9 19:04:15 INFO - PROCESS | 5015 | 19:04:15 INFO - PROCESS | 5015 | [#5] yui.html Cycles:180 Average:16346.76 Median:42856.57 stddev:22423.52 (52.3%) stddev-sans-first:22455.47 19:04:15 INFO - PROCESS | 5015 | Values: 655.6 659.9 660.7 696.3 696.4 12819.7 13664.5 13727.3 13759.3 13759.8 12335.4 12969.8 13156.2 14516.9 14529.3 774.3 792.7 798.2 799.5 799.6 793.1 795.5 795.9 796.0 796.5 839.6 840.5 841.3 841.6 841.8 828.2 831.7 832.0 833.2 833.9 608.6 608.6 609.7 610.4 610.8 12252.1 12265.1 12279.0 12283.8 12284.4 12495.0 12550.4 12754.7 13065.6 13379.9 9927.6 10133.4 10187.0 10474.6 10508.3 3301.1 3309.2 3309.4 3350.1 3392.8 40010.8 56979.4 57031.7 61444.4 62634.4 9830.8 9972.9 10152.1 10286.3 10359.1 19231.3 19294.8 19298.4 19616.0 19642.1 6497.5 6608.7 6640.8 6779.4 6894.4 3559.2 3636.2 3680.1 3687.2 3703.7 2744.6 2835.9 2844.9 2845.1 2845.4 492.6 495.5 502.5 502.5 502.6 529.6 558.8 561.6 572.9 586.2 7546.2 7599.2 7625.2 7865.6 8387.5 475.2 561.6 562.9 571.7 573.6 3689.6 4000.8 4011.6 4097.0 4121.8 90362.0 90505.5 92555.0 93718.1 94047.0 49104.5 66786.0 66856.3 79817.0 83059.0 44312.8 72290.0 73505.6 74825.6 76030.0 8011.6 9021.1 9152.8 9218.4 9254.1 30947.7 40535.0 42348.6 43364.6 43461.0 39319.4 40663.0 41377.4 45984.8 59598.1 11085.2 11138.5 11175.3 11423.9 11455.8 12140.3 13321.3 13560.4 13626.0 13731.6 11783.8 11974.1 12011.1 12348.7 12521.7 12028.6 12433.8 12724.3 12809.2 13628.2 11873.0 11973.3 12105.5 12622.3 12629.4 10626.8 10665.6 10793.1 10930.6 11243.7 9749.2 9818.4 9892.6 10016.9 10476.1 19:04:15 INFO - PROCESS | 5015 | -------- Summary: end -------- 19:04:15 INFO - PROCESS | 5015 | 19:04:16 INFO - TEST-INFO | 5015: exit 0 19:04:16 INFO - TEST-OK | dromaeo_css | took 1119847ms 19:04:16 INFO - TEST-START | kraken 19:04:16 INFO - operating with platform_type : linux_ 19:04:16 INFO - Initialising browser for kraken test... 19:04:16 INFO - TEST-INFO | started process 5207 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvd0at6/profile http://localhost:37112/getInfo.html) 19:04:20 INFO - PROCESS | 5207 | __metrics Screen width/height:1600/1200 19:04:20 INFO - PROCESS | 5207 | colorDepth:24 19:04:20 INFO - PROCESS | 5207 | Browser inner width/height: 1024/697 19:04:20 INFO - PROCESS | 5207 | __metrics 19:04:21 INFO - PROCESS | 5207 | 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" 19:04:21 INFO - PROCESS | 5207 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:04:22 INFO - TEST-INFO | 5207: exit 0 19:04:22 INFO - Browser initialized. 19:04:22 INFO - Running cycle 1/1 for kraken test... 19:04:22 INFO - TEST-INFO | started process 5288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvd0at6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:34 INFO - PROCESS | 5288 | RSS: Main: 159227904 19:04:34 INFO - PROCESS | 5288 | 19:04:37 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37112/tests/kraken/driver.html?testName=audio-beat-detection) 19:04:38 INFO - PROCESS | 5288 | RSS: Main: 218075136 19:04:38 INFO - PROCESS | 5288 | 19:04:40 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37112/tests/kraken/driver.html?testName=audio-dft) 19:04:41 INFO - PROCESS | 5288 | RSS: Main: 206442496 19:04:41 INFO - PROCESS | 5288 | 19:04:44 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37112/tests/kraken/driver.html?testName=audio-fft) 19:04:45 INFO - PROCESS | 5288 | RSS: Main: 271577088 19:04:45 INFO - PROCESS | 5288 | 19:04:47 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37112/tests/kraken/driver.html?testName=audio-oscillator) 19:04:48 INFO - PROCESS | 5288 | RSS: Main: 182288384 19:04:48 INFO - PROCESS | 5288 | 19:04:50 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37112/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:04:50 INFO - PROCESS | 5288 | RSS: Main: 185716736 19:04:50 INFO - PROCESS | 5288 | 19:04:54 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37112/tests/kraken/driver.html?testName=imaging-darkroom) 19:04:54 INFO - PROCESS | 5288 | RSS: Main: 222818304 19:04:54 INFO - PROCESS | 5288 | 19:04:58 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37112/tests/kraken/driver.html?testName=imaging-desaturate) 19:04:58 INFO - PROCESS | 5288 | RSS: Main: 223137792 19:04:58 INFO - PROCESS | 5288 | 19:05:01 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37112/tests/kraken/driver.html?testName=json-parse-financial) 19:05:02 INFO - PROCESS | 5288 | RSS: Main: 222412800 19:05:02 INFO - PROCESS | 5288 | 19:05:03 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37112/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:05:04 INFO - PROCESS | 5288 | RSS: Main: 211263488 19:05:04 INFO - PROCESS | 5288 | 19:05:06 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:05:07 INFO - PROCESS | 5288 | RSS: Main: 214007808 19:05:07 INFO - PROCESS | 5288 | 19:05:09 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:05:09 INFO - PROCESS | 5288 | RSS: Main: 212013056 19:05:09 INFO - PROCESS | 5288 | 19:05:12 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:05:13 INFO - PROCESS | 5288 | RSS: Main: 225869824 19:05:13 INFO - PROCESS | 5288 | 19:05:16 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:05:16 INFO - PROCESS | 5288 | RSS: Main: 225525760 19:05:16 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | Cycle 1(1): loaded http://localhost:37112/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37112/tests/kraken/driver.html?testName=ai-astar) 19:05:18 INFO - PROCESS | 5288 | RSS: Main: 267685888 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | __start_tp_report 19:05:18 INFO - PROCESS | 5288 | _x_x_mozilla_page_load 19:05:18 INFO - PROCESS | 5288 | _x_x_mozilla_page_load_details 19:05:18 INFO - PROCESS | 5288 | |i|pagename|runs| 19:05:18 INFO - PROCESS | 5288 | |0;ai-astar;105;113;113;113;114;113;113;113;112;114 19:05:18 INFO - PROCESS | 5288 | |1;audio-beat-detection;154;123;124;176;122;129;147;134;156;124 19:05:18 INFO - PROCESS | 5288 | |2;audio-dft;161;161;162;160;161;160;160;161;160;162 19:05:18 INFO - PROCESS | 5288 | |3;audio-fft;76;76;77;118;75;76;74;75;98;75 19:05:18 INFO - PROCESS | 5288 | |4;audio-oscillator;86;86;118;84;84;84;128;85;84;125 19:05:18 INFO - PROCESS | 5288 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;116;117;116 19:05:18 INFO - PROCESS | 5288 | |6;imaging-darkroom;147;147;146;146;146;147;146;147;146;147 19:05:18 INFO - PROCESS | 5288 | |7;imaging-desaturate;95;95;96;95;95;95;95;96;95;95 19:05:18 INFO - PROCESS | 5288 | |8;json-parse-financial;63;62;61;62;63;64;63;62;64;63 19:05:18 INFO - PROCESS | 5288 | |9;json-stringify-tinderbox;65;65;65;65;66;66;65;65;66;68 19:05:18 INFO - PROCESS | 5288 | |10;stanford-crypto-aes;78;77;78;79;78;79;79;78;84;82 19:05:18 INFO - PROCESS | 5288 | |11;stanford-crypto-ccm;126;136;124;127;125;127;127;135;211;130 19:05:18 INFO - PROCESS | 5288 | |12;stanford-crypto-pbkdf2;162;166;164;163;161;165;164;161;160;161 19:05:18 INFO - PROCESS | 5288 | |13;stanford-crypto-sha256-iterative;66;65;66;66;66;66;65;68;64;64 19:05:18 INFO - PROCESS | 5288 | __end_tp_report 19:05:18 INFO - PROCESS | 5288 | __start_cc_report 19:05:18 INFO - PROCESS | 5288 | _x_x_mozilla_cycle_collect,1915 19:05:18 INFO - PROCESS | 5288 | __end_cc_report 19:05:18 INFO - PROCESS | 5288 | __startTimestamp1468634718669__endTimestamp 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | ------- Summary: start ------- 19:05:18 INFO - PROCESS | 5288 | Number of tests: 14 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:2.63 (2.3%) stddev-sans-first:0.60 19:05:18 INFO - PROCESS | 5288 | Values: 105.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 112.0 114.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:140.50 stddev:18.46 (13.1%) stddev-sans-first:18.75 19:05:18 INFO - PROCESS | 5288 | Values: 154.0 123.0 124.0 176.0 122.0 129.0 147.0 134.0 156.0 124.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 19:05:18 INFO - PROCESS | 5288 | Values: 161.0 161.0 162.0 160.0 161.0 160.0 160.0 161.0 160.0 162.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.00 stddev:14.51 (19.1%) stddev-sans-first:15.23 19:05:18 INFO - PROCESS | 5288 | Values: 76.0 76.0 77.0 118.0 75.0 76.0 74.0 75.0 98.0 75.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:84.00 stddev:18.99 (22.6%) stddev-sans-first:19.76 19:05:18 INFO - PROCESS | 5288 | Values: 86.0 86.0 118.0 84.0 84.0 84.0 128.0 85.0 84.0 125.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:05:18 INFO - PROCESS | 5288 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 19:05:18 INFO - PROCESS | 5288 | Values: 147.0 147.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 147.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:05:18 INFO - PROCESS | 5288 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:0.95 (1.5%) stddev-sans-first:1.00 19:05:18 INFO - PROCESS | 5288 | Values: 63.0 62.0 61.0 62.0 63.0 64.0 63.0 62.0 64.0 63.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:65.50 stddev:0.97 (1.5%) stddev-sans-first:1.00 19:05:18 INFO - PROCESS | 5288 | Values: 65.0 65.0 65.0 65.0 66.0 66.0 65.0 65.0 66.0 68.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:2.15 (2.7%) stddev-sans-first:2.24 19:05:18 INFO - PROCESS | 5288 | Values: 78.0 77.0 78.0 79.0 78.0 79.0 79.0 78.0 84.0 82.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:128.50 stddev:26.38 (20.5%) stddev-sans-first:27.69 19:05:18 INFO - PROCESS | 5288 | Values: 126.0 136.0 124.0 127.0 125.0 127.0 127.0 135.0 211.0 130.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.50 stddev:2.00 (1.2%) stddev-sans-first:2.11 19:05:18 INFO - PROCESS | 5288 | Values: 162.0 166.0 164.0 163.0 161.0 165.0 164.0 161.0 160.0 161.0 19:05:18 INFO - PROCESS | 5288 | 19:05:18 INFO - PROCESS | 5288 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.17 (1.8%) stddev-sans-first:1.24 19:05:18 INFO - PROCESS | 5288 | Values: 66.0 65.0 66.0 66.0 66.0 66.0 65.0 68.0 64.0 64.0 19:05:18 INFO - PROCESS | 5288 | -------- Summary: end -------- 19:05:18 INFO - PROCESS | 5288 | 19:05:19 INFO - TEST-INFO | 5288: exit 0 19:05:19 INFO - TEST-OK | kraken | took 63130ms 19:05:19 INFO - SUITE-END | took 1182s 19:05:19 INFO - Completed test suite (00:19:43) 19:05:19 INFO - javascript benchmark 19:05:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2638.287662331171, 2652.151311580295, 2681.95977060344, 2690.3274181454635, 2692.8384296942186, 4723.149833030053, 4741.549552792483, 4786.521347865212, 4817.8795530111765, 4876.512348765126, 4811.0, 4819.807207711696, 4821.590164835984, 4840.7579621018895, 4853.5389138031915, 4354.477462704472, 4411.448568928884, 4422.645934892699, 4483.708558943675, 4541.863744087683, 4453.131639330332, 4472.3515090311885, 4500.639948804088, 4505.481869585007, 4533.727976321431, 4295.935560966588, 4316.611504964569, 4318.244307246251, 4331.740095594275, 4352.91294174118, 4328.61042506176, 4346.56534346566, 4380.299152135675, 4395.296752519582, 4415.359772832921, 4390.165868484977, 4400.955990440087, 4404.845830395952, 4419.40338054365, 4421.6904816662845, 4695.389599352096, 4716.103940251375, 4739.644526660514, 4772.141014617368, 4804.711717296938, 5117.974410127963, 5134.0, 5144.099782538041, 5146.099432599281, 5156.742162891878, 5103.081445339837, 5113.923291150636, 5124.4875512448825, 5137.717425541594, 5192.55863251624, 4804.279358096313, 4859.24681674334, 4869.245266983623, 4878.780454879539, 4886.364772579542, 5336.839894803162, 5339.385970613429, 5349.893002139936, 5350.010248104113, 5352.357717073976, 4888.780004899717, 4931.112399768004, 4963.6525443218625, 4976.278439626234, 5014.247862820606, 5116.897662046738, 5157.045946499909, 5188.0661480933795, 5202.323697919246, 5213.452587478336, 5086.160783470686, 5102.158143906214, 5104.0, 5105.310783044316, 5138.075146473596, 4877.926831097537, 5038.899222015539, 5061.620378471629, 5063.316452278968, 5076.898462030738, 4331.45856767904, 4335.089631177488, 4354.542773008852, 4381.9342709859375, 4382.145481631052, 3789.621037896188, 3803.486529318562, 3807.276617442677, 3839.6352346527037, 3845.115623406632, 4284.421603083606, 4335.631471324973, 4413.823447062082, 4448.35498852671, 4461.308497182942, 5170.896582068338, 5193.532582067632, 5194.844154675366, 5199.1161502544655, 5239.921401178985, 4462.241418958784, 4467.218236808546, 4469.597736203692, 4505.053938672915, 4517.909641807146, 4435.290353543418, 4481.910361792811, 4545.704529205591, 4593.0, 4624.121416930772, 4847.951520484891, 4870.878228044327, 4875.68308059975, 4909.656324057281, 4923.753812309442, 5132.666376685504, 5160.664556803796, 5165.096108180978, 5169.457206993212, 5171.198464238049, 4760.952390476189, 4831.130396528518, 4855.6115510758345, 4884.535969082932, 4928.162212423825, 5043.646944713835, 5051.267566202953, 5056.82301119459, 5060.569851562658, 5113.079645663816, 5147.845564633067, 5155.639105262596, 5157.07172708916, 5160.948390516047, 5161.354830646169, 4903.41159060915, 4930.038642464685, 4941.777620007037, 4947.851564453073, 4968.22992436173, 4942.382202224722, 4974.626902982219, 4975.0, 4975.651704380659, 5018.5232402921665, 4790.496997815179, 4889.266610008527, 4895.583875370491, 4895.657303988686, 4954.0, 4907.509249075064, 5053.191489361684, 5073.264376665436, 5095.770690319018, 5098.388193416814, 5001.8499445016705, 5006.4743201963265, 5007.574356179765, 5007.924881126859, 5058.038972595195], "value": 4712.443284110934, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.9774409023637, 569.7236840132535, 581.3430823169817, 590.3771521045295, 592.9110633404991, 26402.075927342546, 37740.43389349159, 39125.0, 39166.21667566647, 43705.34441983373, 40165.0, 40576.0, 40619.390709139385, 40630.7968460158, 40811.0, 7524.811879703024, 7681.3086822186, 7860.096088949758, 7863.174366691501, 7866.7639970800965, 7466.664000120008, 7666.425018123635, 7726.884096738553, 9865.0, 10160.796784064314, 9470.66852660157, 9489.240860731159, 9524.380915240487, 9561.99599042101, 9578.042195780436, 10166.4916754162, 10181.745456363577, 10183.59265629374, 10191.847122293171, 10237.078662920372, 7605.353544948686, 9758.512074396253, 10124.34191777532, 10165.085142337226, 10452.425116618582, 38799.224015519816, 39017.0, 39060.60939390598, 39246.607533924864, 39347.40978885319, 2543.910963116282, 2814.6059551662784, 3105.3634005028916, 3212.3254116635535, 3421.538092357525, 21754.0, 29437.70562294371, 31245.37509249825, 31582.526262106087, 32233.83883080593, 3104.937901241985, 3122.687731226882, 3158.078081066963, 3176.0630613968933, 3184.9044528664176, 257476.0, 258007.0, 259080.0, 259537.0, 259561.0, 52235.73882130641, 52893.73553132287, 52978.735106324995, 53174.468255317726, 53236.0, 43629.0, 44423.55576444194, 44475.55524444779, 44546.77726611346, 48288.7585562077, 14069.788953165711, 14250.073745206528, 14279.286035698382, 14313.499027533986, 14326.713465730836, 9080.137386948229, 10300.896991030144, 10354.844677329846, 10374.948125259325, 10380.584776609003, 15269.923650381677, 15606.0, 15781.605459863365, 15927.442539511294, 15985.760213596805, 6161.630302181883, 6255.435271538739, 6298.685065746694, 6299.181106456132, 6323.936760632427, 834.4909605140859, 918.8070505193984, 977.9511024448749, 995.4375777685584, 1040.9167266618804, 28182.29544261369, 28265.72804223815, 28643.2839179018, 28675.56986645202, 28872.711272887, 955.1690029674228, 976.7167521418709, 980.9754756131153, 984.8719666443317, 994.6667866264737, 14804.481843135654, 15258.237088145548, 15263.236838158047, 15536.378544858306, 15558.84441155574, 357472.0, 358306.0, 361355.0, 362493.0, 362780.0, 48724.15310162732, 48822.511774881794, 49012.0, 49059.0, 49064.50935490671, 1062.293574772783, 1154.2324354304294, 1211.0432758121162, 1218.2020776391478, 1228.91397602167, 27783.58324625173, 29139.417211655225, 29170.416591667625, 29468.852655736584, 29482.410351793274, 41369.1726165469, 41506.58493415148, 41507.37738933858, 41550.58449415588, 41885.79057104817, 244646.0, 248670.0, 248712.0, 249242.0, 249281.0, 30466.543001854545, 31419.528707068956, 31618.0, 31633.0, 34354.48468272928, 32583.837080814443, 33302.66697332996, 38541.6145838538, 39205.41191882181, 39361.80319098386, 29978.100656980325, 30453.0, 30613.23466913345, 30617.23456913506, 31453.0, 33507.0, 34279.82860085684, 34420.0, 34538.82730586331, 34593.65406345904, 32958.0, 33852.153696157795, 34045.14887127842, 34158.65841341555, 34215.48676769801, 30121.246968825955, 31100.46894134705, 31199.84400077985, 31783.682163178073, 35120.64879351276, 25185.370365741004, 25225.369365766004, 25467.745322546536, 25617.231483055537, 25812.22563323103], "value": 17382.982042849515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.12428110394495, 375.962403759624, 376.78523241752197, 386.42422790042843, 386.6269050366396, 16380.91809540952, 17673.29306827727, 17919.910400447978, 18591.442256732287, 18605.441836744903, 17243.396481123164, 17590.912045439756, 17904.641907161847, 17921.731174032368, 18258.81741182588, 5154.149565321717, 5346.599005074616, 5373.81191658292, 5392.164214546732, 5396.811111611094, 3707.277080969212, 5320.707361095138, 6001.699915004254, 6319.74721011159, 6333.524985626073, 6106.877862442749, 6348.0477928310665, 6356.68216589171, 6378.532937424372, 6381.521385896053, 6205.0, 6232.968835155841, 6236.501079913596, 6269.0909818076125, 6277.623342599436, 3449.3963556377785, 3615.349237137314, 3616.2044350242904, 3667.9266414671824, 5197.636165468419, 20616.175352986014, 21099.78900211009, 21866.781332186634, 21903.233386831544, 22166.889165554232, 2042.4791678122133, 2043.2644248070683, 2069.8344132469365, 2309.3302942146925, 2359.882005899698, 13092.083554151213, 13596.59210223695, 13755.931220343935, 14052.0, 14179.361928713233, 1670.5072003758921, 1787.2046939112101, 1806.4761219246402, 1830.8718389712726, 1837.0355563329226, 66752.3324766751, 68999.0, 69151.3084869155, 69479.0, 69494.65252673755, 9750.951245243728, 10037.397756134655, 10113.14038306752, 10129.696109116738, 10391.428471434143, 13475.124116932368, 13586.320683965761, 13604.93197534006, 13639.181649100887, 13659.0, 7330.706771729071, 7363.8159046024275, 7746.535207887545, 7820.68717251315, 7840.255175758294, 7556.357709594636, 7559.1684914659345, 7563.9621801890635, 7671.923280767121, 7678.424118191158, 6183.288921773964, 6191.535634827406, 6250.624962502265, 6324.462420694201, 6440.808450436684, 1659.1787065402461, 1683.4360489236244, 1761.207456644515, 1791.3192986664983, 1846.6768315544725, 721.8664547058811, 755.112742527528, 791.525084949026, 839.4459656626597, 854.0306751836586, 16337.18314084291, 16854.662906741796, 16979.320827166775, 17230.65538689219, 17320.566985825455, 751.7331347371604, 777.859985202669, 780.3328154427883, 835.6949713354581, 859.6690274244336, 9220.8616870747, 9296.0, 9472.289578281656, 9576.186024188022, 9674.177694895872, 366186.0, 366446.85201757617, 370392.0, 380464.0, 385326.0, 67389.6630516854, 69941.30058699349, 70201.64899175472, 70361.64819175974, 70464.0, 894.5706061090713, 903.8779764731677, 907.5462268865567, 913.7167478081816, 928.0672923711627, 15824.287907043981, 15895.205239737736, 16011.839881601501, 16108.0, 16144.596385090466, 35610.64389356177, 36041.0, 36339.0, 36481.27037459289, 36693.0, 46460.7676961613, 47989.5201047999, 48730.75634621804, 48765.0, 50115.49884501254, 7589.924100758922, 7700.537967721954, 7723.9227607723205, 7941.086775020715, 8028.6387112578905, 12054.638360849189, 12111.636650900487, 12344.567940122053, 12552.309622970459, 12680.492780288685, 10433.113185379329, 10506.474676266309, 10571.577136914744, 10833.67498975032, 11108.166887483309, 4816.831410900601, 6477.578957367945, 10882.388226825664, 10935.507902144263, 10987.39569323695, 10799.244052916534, 11111.0, 11122.165837562377, 11141.331520108819, 11286.774264514499, 6621.370969757865, 7039.1553013638695, 7283.053203083778, 7374.557526548424, 7995.200479952191, 7772.067351917806, 10203.693889183336, 10819.404932728776, 10882.945585272022, 11037.0], "value": 8400.920892761922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1190.2382475215863, 1201.321253491777, 1319.6041187643707, 1399.6920677450958, 1410.7037522120347, 6990.196127445332, 7192.748253811118, 7682.6158692065455, 8155.6329965151635, 8228.218319259668, 7668.578228197447, 7914.999999999985, 7927.8414431711335, 8090.91909080909, 8119.26926576608, 2222.6888235646984, 2249.3589327041796, 2250.437390652337, 2251.3583628665833, 2252.200468833565, 1863.7763468383812, 2038.8572799904014, 2085.7601375841746, 2088.56140210556, 2210.403191138392, 2219.7891200335944, 2241.529278851443, 2245.932622021342, 2247.9887600561974, 2248.2580748353002, 2142.1752625239237, 2207.768184340637, 2211.94470138247, 2212.668099785029, 2217.5675743230076, 2146.205903815598, 2183.4213933307688, 2186.30038387716, 2235.0947866114248, 2236.966445503319, 8162.510249385056, 8213.260806527382, 8237.382196335298, 8282.0, 8286.171382861727, 4585.12882552314, 4718.669693121483, 4720.772599124252, 4725.456572494173, 4728.598069164125, 2764.903228386997, 2780.527310357244, 2887.5524293734507, 2890.0462847260355, 2895.3051267695814, 784.9411294152961, 813.3005615171007, 827.2472050434076, 827.4745536584252, 828.668532586964, 10883.23817332787, 10997.12023038163, 11008.724781880357, 11028.393438360887, 11039.0, 3960.980195099006, 4108.979455102705, 4145.0880806222585, 4211.073563815956, 4214.4099826024385, 8596.398252122417, 8654.264387527004, 8655.6537738491, 8661.956690216508, 8667.653293868174, 4563.817447302137, 4565.429321334833, 4566.429196350457, 4567.9086418271445, 4570.634349252052, 3572.9464058039152, 4207.810648520824, 4225.514065882463, 4231.640310573574, 4233.216854881856, 3080.753539716817, 3206.150370151912, 3598.2803439312243, 3602.369585322558, 3647.6534729200685, 1043.3270540501485, 1049.2655141401049, 1051.3008849115406, 1080.1412876762993, 1080.7460246841986, 761.208343322949, 770.7726220764888, 777.3897490469953, 792.4690457393444, 797.6171437709816, 4739.573438390561, 4893.437254715682, 4898.363212782387, 4971.1549036663855, 5015.272785446163, 835.5613303015965, 855.4140413816556, 868.847951608466, 875.4397185801092, 878.1745159550003, 3181.4750566156326, 3188.58548388708, 3299.5050742388353, 3319.7676162668863, 3370.5449764281507, 9794.265430092772, 10042.447665378328, 10050.447225402528, 10150.593976241016, 10159.4920253987, 10058.698239052841, 10074.496275186211, 10081.143102836195, 10081.546330415149, 10097.0, 7546.698132074765, 7628.54228746277, 7633.541987480658, 7636.465447418627, 7640.656170472342, 5056.191009438472, 5175.223716442413, 5343.251944727819, 5366.865828354323, 5482.259894914295, 9012.369134160546, 9313.767155821159, 9371.906280937103, 9501.477418742035, 9519.952400237955, 9422.387544809566, 9619.615215391306, 9623.518824058909, 9639.469829159463, 9643.614255429704, 6176.4441200292185, 6724.327567243432, 6884.586924784529, 6885.758998435031, 7086.185088714864, 7836.37309015266, 7838.3337416320255, 7860.5676687782725, 7902.0, 7916.366690664848, 6855.931440685529, 6968.198657154492, 7144.3212894774915, 7163.283671633004, 7257.419406447366, 6905.482088843256, 6996.475264355092, 7023.824404389931, 7044.049053377728, 7111.43108551325, 6230.376962303733, 6464.838379040561, 6467.932791089417, 6607.603543787388, 6630.3038180992235, 4453.287474004143, 4490.640748740027, 4550.180967425763, 4606.585407313358, 4629.4676112247525, 4464.084862603094, 4527.886802829824, 4566.292224705043, 4592.770361481979, 4653.976730116327], "value": 4116.774253556392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.7462306285783, 306.9324748555317, 307.17982985428904, 308.4524968181479, 310.86477382338677, 4847.515248475151, 4848.927266091011, 4859.416869975604, 4868.367112275404, 4940.876478088049, 4820.373351464314, 4854.5679024687715, 4860.343853579766, 4860.6597538172255, 4864.440589332228, 3575.964240357596, 3689.501917241165, 3747.1381582236104, 3818.217265460588, 3837.769733815966, 3789.7726136431766, 3810.8856734298015, 3811.2377524495073, 3814.5422549293985, 3823.9235215295676, 3763.7929913854728, 3766.905827354324, 3775.6413140751583, 3797.854212370016, 3798.9050273743237, 3563.1626567756557, 3670.596234414226, 3809.733318667694, 3824.235152969389, 3845.6731177849915, 3387.7120444762227, 3392.915177120567, 3397.0, 3404.7616666833333, 3405.863765449379, 4796.952030479685, 4798.424189097295, 4803.231482962709, 4806.807727690888, 4821.68659037165, 4249.893752656209, 4374.168907907488, 4398.868033958986, 4404.669649776248, 4432.15789000089, 4996.925046124311, 5038.143515602356, 5039.269305950616, 5043.142665746832, 5080.466551012164, 3716.4425336199515, 3720.441933709938, 3722.274156539476, 3732.328180927432, 3734.271816995687, 5104.157813960692, 5347.411784703719, 5355.7857685692525, 5359.169328754049, 5359.437259087778, 3772.86794962175, 3785.848566057382, 3791.6208379161862, 3793.943090853639, 3809.6760975804737, 3833.5783063862955, 3838.1939792643857, 3840.4431357453013, 3849.268638958589, 3853.845846166122, 4352.173087113439, 4352.69531132824, 4354.6951713380395, 4367.2575662137515, 4368.868933931987, 4805.255185446262, 5027.84916452507, 5049.82325618609, 5050.924236136461, 5104.668196567211, 1282.6793301674581, 1296.112163168214, 1302.8306320178506, 1306.1053178572708, 1315.7171208190284, 813.7762115418188, 833.4124442268186, 837.4431003382682, 934.9392289501296, 945.6548359848744, 4400.09797991411, 4440.156370289635, 4441.755703436277, 4477.1269602427865, 4489.820407183676, 5180.4560521145495, 5191.195364718475, 5208.479152084837, 5230.3985041719925, 5246.134387825966, 4329.203380597017, 4348.34774783785, 4455.799489023002, 4469.033625204965, 4471.396361491222, 2745.9038933637225, 2746.615473833665, 2764.3227409284855, 2773.5839624056553, 2776.236534952904, 4882.218844984785, 4885.242787367893, 4909.386326709161, 4913.263010548375, 4920.163572192736, 5060.797568097235, 5250.789968401221, 5296.761645725952, 5313.707746073926, 5393.541548968303, 4966.478519755374, 4967.279744437036, 4973.651844370932, 4974.37820272466, 4978.6017118630425, 5247.9737601311745, 5253.448387919367, 5268.0, 5276.604254680838, 5287.524122828964, 4869.342638743724, 5147.742612869416, 5152.871178220575, 5170.224466330081, 5182.922256166236, 4963.4540200577185, 4968.900621987467, 4970.776315065903, 4971.37857767779, 4988.12707776145, 3732.749528907846, 3744.64425879541, 3749.887503374903, 3759.755615884959, 3762.4168253920684, 5105.7702403391195, 5231.267622532772, 5311.362636483646, 5312.867178320418, 5332.706701131475, 5056.469070747666, 5085.821996230115, 5087.481696560227, 5091.9490805092955, 5101.642884998015, 5071.137906555969, 5083.29709547291, 5091.974540127424, 5093.949060509348, 5114.5652619527755, 4803.855884323476, 5022.74886255693, 5026.824061157842, 5053.974730126326, 5054.570361519165, 4892.241702536113, 4970.801167953241, 5137.8715532112, 5143.742812859267, 5149.918517111299, 4999.925001125059, 5022.246663000433, 5023.899522009467, 5027.461132128252, 5032.899342013213], "value": 3849.231202475531, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.573876979963, 659.8977158540428, 660.7293895218666, 696.348913765629, 696.4498046543231, 12819.743605127891, 13664.521741739016, 13727.313634318269, 13759.312034398277, 13759.79360309596, 12335.383230838466, 12969.80545291819, 13156.210627362365, 14516.927415362934, 14529.273536323195, 774.3263360876045, 792.6987744657022, 798.1579433697447, 799.4643588795492, 799.6441583495347, 793.0681449297082, 795.4988357334871, 795.9442839001243, 796.0288448093316, 796.5419883566943, 839.5508403004394, 840.504102579478, 841.3395484544634, 841.6212704283066, 841.8316336732647, 828.2131974624101, 831.6881169561415, 832.01406333495, 833.1918039501674, 833.8624127019035, 608.5618354784548, 608.5831205624118, 609.704293417692, 610.4170517156106, 610.8442347201435, 12252.081093917903, 12265.141440099196, 12279.0, 12283.815742763865, 12284.447199876027, 12495.0, 12550.435230414654, 12754.68113297175, 13065.608031759062, 13379.86620133796, 9927.610134581164, 10133.391996480235, 10187.0, 10474.58101675932, 10508.316959397614, 3301.057649337117, 3309.1561651778884, 3309.3712194682935, 3350.1122202616325, 3392.7794693344854, 40010.79994600038, 56979.43020569824, 57031.71484142594, 61444.38555614431, 62634.37365626331, 9830.803383932282, 9972.850407243897, 10152.137068349273, 10286.268321518708, 10359.119474844723, 19231.326903558307, 19294.807051929583, 19298.4210473686, 19616.0, 19642.11610477532, 6497.512686548528, 6608.7026083825385, 6640.800775976729, 6779.35596118368, 6894.413974812097, 3559.18138828071, 3636.2363903580545, 3680.1351682354625, 3687.170386663033, 3703.703703703751, 2744.6157537944705, 2835.929101772431, 2844.928876778056, 2845.132234668446, 2845.445138198073, 492.6034542193569, 495.50202046942593, 502.5276240334147, 502.5376653478808, 502.6230327254559, 529.5895680847374, 558.7988324203284, 561.5647872898454, 572.8882867840814, 586.1617886422372, 7546.20764819697, 7599.240075992467, 7625.199354067854, 7865.567393793396, 8387.454815436977, 475.23961661341576, 561.5591760468013, 562.8986782379129, 571.6684323092679, 573.5698226330252, 3689.612590677994, 4000.8199631016673, 4011.5787842276723, 4096.979515102465, 4121.752694838319, 90362.0, 90505.54747226222, 92555.0, 93718.0628193723, 94047.0, 49104.50895491071, 66786.0, 66856.33143668598, 79817.0, 83059.0, 44312.77843610761, 72290.0, 73505.63247183837, 74825.62587187029, 76030.0, 8011.559364235023, 9021.097890210927, 9152.771180720536, 9218.400803947721, 9254.12085851843, 30947.69052309448, 40534.986625334604, 42348.5765142357, 43364.56635433605, 43461.0, 39319.41020884632, 40663.0, 41377.37933930933, 45984.770076149405, 59598.10602840874, 11085.22403431752, 11138.498767555317, 11175.273607215506, 11423.885761142283, 11455.828162577734, 12140.339698532332, 13321.33393330349, 13560.389782459615, 13626.0, 13731.588052358446, 11783.823242651539, 11974.101942354184, 12011.099167562643, 12348.691282718004, 12521.686957825763, 12028.63914082579, 12433.813492797795, 12724.300163491096, 12809.23144611289, 13628.182309061489, 11873.0, 11973.281603103842, 12105.515779368727, 12622.305773182563, 12629.431675574804, 10626.787464250827, 10665.573377064831, 10793.136549076213, 10930.617428389969, 11243.718907027063, 9749.220062395134, 9818.410895346302, 9892.554835032297, 10016.899831001596, 10476.109530689671], "value": 6451.926184158973, "unit": "score"}], "name": "dromaeo_css", "value": 6425.315298371503, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0, 114.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 124.0, 176.0, 122.0, 129.0, 147.0, 134.0, 156.0, 124.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 118.0, 75.0, 76.0, 74.0, 75.0, 98.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 118.0, 84.0, 84.0, 84.0, 128.0, 85.0, 84.0, 125.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 62.0, 63.0, 64.0, 63.0, 62.0, 64.0, 63.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 68.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 79.0, 78.0, 79.0, 79.0, 78.0, 84.0, 82.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 136.0, 124.0, 127.0, 125.0, 127.0, 127.0, 135.0, 211.0, 130.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 164.0, 163.0, 161.0, 165.0, 164.0, 161.0, 160.0, 161.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 68.0, 64.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1521.3, "alertThreshold": 2.0}]} 19:05:19 INFO - Return code: 0 19:05:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:05:19 INFO - # TBPL SUCCESS # 19:05:19 INFO - Running post-action listener: _resource_record_post_action 19:05:19 INFO - ##### 19:05:19 INFO - ##### Finished run-tests step (success) 19:05:19 INFO - ##### 19:05:19 INFO - Running post-run listener: _resource_record_post_run 19:05:20 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287686656; Read time: 12; Write time: 216936 19:05:20 INFO - TinderboxPrint: CPU usage
12.9% 19:05:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:05:20 INFO - TinderboxPrint: I/O write bytes / time
287,686,656 / 216,936 19:05:20 INFO - TinderboxPrint: CPU idle
8,337.3 (87.0%) 19:05:20 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 19:05:20 INFO - TinderboxPrint: Swap in / out
0 / 0 19:05:20 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 684032; Read time: 0; Write time: 40 19:05:20 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 286388224; Read time: 0; Write time: 216896 19:05:20 INFO - Running post-run listener: _upload_blobber_files 19:05:20 INFO - Blob upload gear active. 19:05:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:05:20 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 19:05:20 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:05:20 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:05:21 INFO - (blobuploader) - INFO - Open directory for files ... 19:05:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:05:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:05:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:21 INFO - (blobuploader) - INFO - Done attempting. 19:05:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:05:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:05:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:25 INFO - (blobuploader) - INFO - Done attempting. 19:05:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:05:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:05:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:25 INFO - (blobuploader) - INFO - Done attempting. 19:05:25 INFO - (blobuploader) - INFO - Iteration through files over. 19:05:25 INFO - Return code: 0 19:05:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:05:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:05:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94ec0a111d0da2283a96aae79e8f0b63df9390d5583fe5868913cf38c938d06f11aaea2a234a472127a0fb9b3e2b8e12be8f786d5c01abb4ad8db8c07bd9a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1dc5c02e233610dbee2193c8cdb6b8d7f5140229433265c2edf3f4a807037a899b2cd19f2de109133a5c655d39a1891ab9d753bd180a390c0add924567623b8b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b36bbf6c33f23897b06040ac80480c64351186716b091e6fba212b7aefa4ae6409a1d4a29370fe08b1978fc87879d38c3bffd03d23e05aae5e94689ea37bb45c"} 19:05:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:05:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:05:25 INFO - Contents: 19:05:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94ec0a111d0da2283a96aae79e8f0b63df9390d5583fe5868913cf38c938d06f11aaea2a234a472127a0fb9b3e2b8e12be8f786d5c01abb4ad8db8c07bd9a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1dc5c02e233610dbee2193c8cdb6b8d7f5140229433265c2edf3f4a807037a899b2cd19f2de109133a5c655d39a1891ab9d753bd180a390c0add924567623b8b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b36bbf6c33f23897b06040ac80480c64351186716b091e6fba212b7aefa4ae6409a1d4a29370fe08b1978fc87879d38c3bffd03d23e05aae5e94689ea37bb45c"} 19:05:25 INFO - Running post-run listener: copy_logs_to_upload_dir 19:05:25 INFO - Copying logs to upload dir... 19:05:25 INFO - mkdir: /builds/slave/test/build/upload/logs 19:05:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.392340 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-15 19:05:26.068137) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 19:05:26.071709) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94ec0a111d0da2283a96aae79e8f0b63df9390d5583fe5868913cf38c938d06f11aaea2a234a472127a0fb9b3e2b8e12be8f786d5c01abb4ad8db8c07bd9a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1dc5c02e233610dbee2193c8cdb6b8d7f5140229433265c2edf3f4a807037a899b2cd19f2de109133a5c655d39a1891ab9d753bd180a390c0add924567623b8b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b36bbf6c33f23897b06040ac80480c64351186716b091e6fba212b7aefa4ae6409a1d4a29370fe08b1978fc87879d38c3bffd03d23e05aae5e94689ea37bb45c"} build_url:https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/RNRqQUtDSvafjoz5QlyoDg/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/Autoland-Non-PGO/sha512/31d94ec0a111d0da2283a96aae79e8f0b63df9390d5583fe5868913cf38c938d06f11aaea2a234a472127a0fb9b3e2b8e12be8f786d5c01abb4ad8db8c07bd9a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1dc5c02e233610dbee2193c8cdb6b8d7f5140229433265c2edf3f4a807037a899b2cd19f2de109133a5c655d39a1891ab9d753bd180a390c0add924567623b8b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b36bbf6c33f23897b06040ac80480c64351186716b091e6fba212b7aefa4ae6409a1d4a29370fe08b1978fc87879d38c3bffd03d23e05aae5e94689ea37bb45c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 19:05:26.097649) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 19:05:26.097978) ========= 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=a0d2d2de893abaa8702a9954000001a7-1468633485.98829-1133733822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 19:05:26.181828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 19:05:26.182151) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 19:05:26.182475) ========= ========= Total master_lag: 0.18 =========