builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-057 starttime: 1468275212.87 results: success (0) buildid: 20160711135528 builduid: f6213b0e1d52462b997b17c0724fb4ef revision: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:32.867230) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:32.867680) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:32.867971) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:32.882999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:32.883290) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 15:13:33-- 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% 104M=0s 2016-07-11 15:13:33 (104 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117569 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.017475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.017768) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021030 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.067702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.068018) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6b329e5cac8634acbfb8a10bcfc441f7498bab23 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6b329e5cac8634acbfb8a10bcfc441f7498bab23 --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=934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 15:13:33,664 truncating revision to first 12 chars 2016-07-11 15:13:33,664 Setting DEBUG logging. 2016-07-11 15:13:33,664 attempt 1/10 2016-07-11 15:13:33,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6b329e5cac86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 15:13:33,985 unpacking tar archive at: mozilla-central-6b329e5cac86/testing/mozharness/ program finished with exit code 0 elapsedTime=0.670668 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.752060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.752391) ========= script_repo_revision: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.752822) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.753114) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 15:13:33.762226) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-11 15:13:33.762541) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:13:34 INFO - MultiFileLogger online at 20160711 15:13:34 in /builds/slave/test 15:13:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:13:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:13:34 INFO - {'append_to_log': False, 15:13:34 INFO - 'base_work_dir': '/builds/slave/test', 15:13:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:13:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:13:34 INFO - 'branch': 'Firefox-Non-PGO', 15:13:34 INFO - 'buildbot_json_path': 'buildprops.json', 15:13:34 INFO - 'config_files': ('talos/linux_config.py',), 15:13:34 INFO - 'default_actions': ('clobber', 15:13:34 INFO - 'read-buildbot-config', 15:13:34 INFO - 'download-and-extract', 15:13:34 INFO - 'populate-webroot', 15:13:34 INFO - 'create-virtualenv', 15:13:34 INFO - 'install', 15:13:34 INFO - 'run-tests'), 15:13:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:13:34 INFO - 'download_minidump_stackwalk': True, 15:13:34 INFO - 'download_symbols': 'ondemand', 15:13:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:13:34 INFO - 'tooltool.py': '/tools/tooltool.py', 15:13:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:13:34 INFO - '/tools/misc-python/virtualenv.py')}, 15:13:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:13:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:13:34 INFO - 'installer_path': 'installer.exe', 15:13:34 INFO - 'log_level': 'info', 15:13:34 INFO - 'log_name': 'talos', 15:13:34 INFO - 'log_to_console': True, 15:13:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:13:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:13:34 INFO - 'opt_config_files': (), 15:13:34 INFO - 'pip_index': False, 15:13:34 INFO - 'sps_profile': False, 15:13:34 INFO - 'sps_profile_interval': 0, 15:13:34 INFO - 'suite': 'dromaeojs', 15:13:34 INFO - 'system_bits': '32', 15:13:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:13:34 INFO - 'title': 'talos-linux64-ix-057', 15:13:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:13:34 INFO - 'use_talos_json': True, 15:13:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:13:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:13:34 INFO - 'work_dir': 'build'} 15:13:34 INFO - ##### 15:13:34 INFO - ##### Running clobber step. 15:13:34 INFO - ##### 15:13:34 INFO - Running pre-action listener: _resource_record_pre_action 15:13:34 INFO - Running main action method: clobber 15:13:34 INFO - rmtree: /builds/slave/test/build 15:13:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:13:34 INFO - Running post-action listener: _resource_record_post_action 15:13:34 INFO - ##### 15:13:34 INFO - ##### Finished clobber step (success) 15:13:34 INFO - ##### 15:13:34 INFO - ##### 15:13:34 INFO - ##### Running read-buildbot-config step. 15:13:34 INFO - ##### 15:13:34 INFO - Running pre-action listener: _resource_record_pre_action 15:13:34 INFO - Running main action method: read_buildbot_config 15:13:34 INFO - Using buildbot properties: 15:13:34 INFO - { 15:13:34 INFO - "project": "", 15:13:34 INFO - "product": "firefox", 15:13:34 INFO - "script_repo_revision": "production", 15:13:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:13:34 INFO - "repository": "", 15:13:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:13:34 INFO - "buildid": "20160711135528", 15:13:34 INFO - "pgo_build": "False", 15:13:34 INFO - "basedir": "/builds/slave/test", 15:13:34 INFO - "buildnumber": 646, 15:13:34 INFO - "slavename": "talos-linux64-ix-057", 15:13:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:13:34 INFO - "platform": "ubuntu64_hw", 15:13:34 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:13:34 INFO - "branch": "mozilla-central", 15:13:34 INFO - "repo_path": "mozilla-central", 15:13:34 INFO - "revision": "6b329e5cac8634acbfb8a10bcfc441f7498bab23", 15:13:34 INFO - "stage_platform": "linux64", 15:13:34 INFO - "builduid": "f6213b0e1d52462b997b17c0724fb4ef", 15:13:34 INFO - "slavebuilddir": "test" 15:13:34 INFO - } 15:13:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:13:34 INFO - Running post-action listener: _resource_record_post_action 15:13:34 INFO - ##### 15:13:34 INFO - ##### Finished read-buildbot-config step (success) 15:13:34 INFO - ##### 15:13:34 INFO - ##### 15:13:34 INFO - ##### Running download-and-extract step. 15:13:34 INFO - ##### 15:13:34 INFO - Running pre-action listener: _resource_record_pre_action 15:13:34 INFO - Running main action method: download_and_extract 15:13:34 INFO - mkdir: /builds/slave/test/build/tests 15:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:34 INFO - https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:13:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:13:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:13:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:13:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:13:35 INFO - Downloaded 1361 bytes. 15:13:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:13:35 INFO - Using the following test package requirements: 15:13:35 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:13:35 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:13:35 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:13:35 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'jsshell-linux-x86_64.zip'], 15:13:35 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:13:35 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:13:35 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:13:35 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:13:35 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:13:35 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:13:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:13:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:35 INFO - https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:13:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:13:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:13:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:13:36 INFO - Downloaded 22737688 bytes. 15:13:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:13:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:13:37 INFO - Return code: 0 15:13:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:37 INFO - https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:13:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:13:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:13:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:13:39 INFO - Downloaded 22737688 bytes. 15:13:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:13:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:13:40 INFO - Return code: 0 15:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:40 INFO - https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:13:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:13:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:13:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:13:41 INFO - Downloaded 14403333 bytes. 15:13:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:13:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:13:42 INFO - Return code: 0 15:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:42 INFO - https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:13:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:13:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:13:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:13:43 INFO - Downloaded 56653985 bytes. 15:13:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:13:43 INFO - mkdir: /builds/slave/test/properties 15:13:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:13:43 INFO - Writing to file /builds/slave/test/properties/build_url 15:13:43 INFO - Contents: 15:13:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:13:44 INFO - Running post-action listener: _resource_record_post_action 15:13:44 INFO - Running post-action listener: set_extra_try_arguments 15:13:44 INFO - ##### 15:13:44 INFO - ##### Finished download-and-extract step (success) 15:13:44 INFO - ##### 15:13:44 INFO - ##### 15:13:44 INFO - ##### Running populate-webroot step. 15:13:44 INFO - ##### 15:13:44 INFO - Running pre-action listener: _resource_record_pre_action 15:13:44 INFO - Running main action method: populate_webroot 15:13:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:13:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:13:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:13:44 INFO - '--tppagecycles', 15:13:44 INFO - '7'], 15:13:44 INFO - 'tests': ['tsvgm']}}, 15:13:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:13:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:13:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:13:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:13:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:13:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:13:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['damp', 'tps']}, 15:13:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'tests': ['damp', 'tps']}, 15:13:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['dromaeo_dom']}, 15:13:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:13:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['basic_compositor_video']}, 15:13:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:13:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['a11yr', 15:13:44 INFO - 'ts_paint', 15:13:44 INFO - 'tpaint', 15:13:44 INFO - 'sessionrestore', 15:13:44 INFO - 'sessionrestore_no_auto_restore', 15:13:44 INFO - 'tabpaint']}, 15:13:44 INFO - 'other-e10s': {'tests': ['a11yr', 15:13:44 INFO - 'ts_paint', 15:13:44 INFO - 'tpaint', 15:13:44 INFO - 'sessionrestore', 15:13:44 INFO - 'sessionrestore_no_auto_restore', 15:13:44 INFO - 'tabpaint']}, 15:13:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:13:44 INFO - 'ts_paint', 15:13:44 INFO - 'tpaint', 15:13:44 INFO - 'sessionrestore', 15:13:44 INFO - 'sessionrestore_no_auto_restore', 15:13:44 INFO - 'tabpaint']}, 15:13:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:13:44 INFO - 'ts_paint', 15:13:44 INFO - 'tpaint', 15:13:44 INFO - 'sessionrestore', 15:13:44 INFO - 'sessionrestore_no_auto_restore', 15:13:44 INFO - 'tabpaint']}, 15:13:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['a11yr', 15:13:44 INFO - 'ts_paint', 15:13:44 INFO - 'tpaint', 15:13:44 INFO - 'sessionrestore', 15:13:44 INFO - 'sessionrestore_no_auto_restore', 15:13:44 INFO - 'tabpaint']}, 15:13:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['a11yr', 15:13:44 INFO - 'ts_paint', 15:13:44 INFO - 'tpaint', 15:13:44 INFO - 'sessionrestore', 15:13:44 INFO - 'sessionrestore_no_auto_restore', 15:13:44 INFO - 'tabpaint']}, 15:13:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['tsvgx', 15:13:44 INFO - 'tsvgr_opacity', 15:13:44 INFO - 'tart', 15:13:44 INFO - 'tscrollx', 15:13:44 INFO - 'cart']}, 15:13:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:13:44 INFO - 'tsvgr_opacity', 15:13:44 INFO - 'tart', 15:13:44 INFO - 'tscrollx', 15:13:44 INFO - 'cart']}, 15:13:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'talos_options': ['--disable-e10s'], 15:13:44 INFO - 'tests': ['tp5o']}, 15:13:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'tests': ['tp5o']}, 15:13:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'talos_options': ['--disable-e10s', 15:13:44 INFO - '--xperf_path', 15:13:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:13:44 INFO - 'tests': ['tp5n']}, 15:13:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:44 INFO - 'talos_options': ['--xperf_path', 15:13:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:13:44 INFO - 'tests': ['tp5n']}}, 15:13:44 INFO - 'talos.zip': {'path': '', 15:13:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:13:44 INFO - Running post-action listener: _resource_record_post_action 15:13:44 INFO - ##### 15:13:44 INFO - ##### Finished populate-webroot step (success) 15:13:44 INFO - ##### 15:13:44 INFO - ##### 15:13:44 INFO - ##### Running create-virtualenv step. 15:13:44 INFO - ##### 15:13:44 INFO - Running pre-action listener: _resource_record_pre_action 15:13:44 INFO - Running main action method: create_virtualenv 15:13:44 INFO - Creating virtualenv /builds/slave/test/build/venv 15:13:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:13:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:13:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:13:44 INFO - Using real prefix '/usr' 15:13:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:13:44 INFO - Installing distribute.............................................................................................................................................................................................done. 15:13:46 INFO - Installing pip.................done. 15:13:46 INFO - Return code: 0 15:13:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:13:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:13:46 INFO - Using env: {'DISPLAY': ':0', 15:13:46 INFO - 'HOME': '/home/cltbld', 15:13:46 INFO - 'LANG': 'en_US.UTF-8', 15:13:46 INFO - 'LANGUAGE': 'en_US:en', 15:13:46 INFO - 'LOGNAME': 'cltbld', 15:13:46 INFO - 'MAIL': '/var/mail/cltbld', 15:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:46 INFO - 'MOZ_NO_REMOTE': '1', 15:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:46 INFO - 'NO_EM_RESTART': '1', 15:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:46 INFO - 'PWD': '/builds/slave/test', 15:13:46 INFO - 'SHELL': '/bin/bash', 15:13:46 INFO - 'SHLVL': '1', 15:13:46 INFO - 'TERM': 'linux', 15:13:46 INFO - 'TMOUT': '86400', 15:13:46 INFO - 'USER': 'cltbld', 15:13:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:46 INFO - '_': '/tools/buildbot/bin/python'} 15:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:13:47 INFO - Cleaning up... 15:13:47 INFO - Return code: 0 15:13:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:47 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:13:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:13:47 INFO - Using env: {'DISPLAY': ':0', 15:13:47 INFO - 'HOME': '/home/cltbld', 15:13:47 INFO - 'LANG': 'en_US.UTF-8', 15:13:47 INFO - 'LANGUAGE': 'en_US:en', 15:13:47 INFO - 'LOGNAME': 'cltbld', 15:13:47 INFO - 'MAIL': '/var/mail/cltbld', 15:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:47 INFO - 'MOZ_NO_REMOTE': '1', 15:13:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:47 INFO - 'NO_EM_RESTART': '1', 15:13:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:47 INFO - 'PWD': '/builds/slave/test', 15:13:47 INFO - 'SHELL': '/bin/bash', 15:13:47 INFO - 'SHLVL': '1', 15:13:47 INFO - 'TERM': 'linux', 15:13:47 INFO - 'TMOUT': '86400', 15:13:47 INFO - 'USER': 'cltbld', 15:13:47 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:47 INFO - '_': '/tools/buildbot/bin/python'} 15:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:47 INFO - Downloading/unpacking psutil>=3.1.1 15:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:13:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:13:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:13:49 INFO - Installing collected packages: psutil 15:13:49 INFO - Running setup.py install for psutil 15:13:49 INFO - building 'psutil._psutil_linux' extension 15:13:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:13:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:13:49 INFO - building 'psutil._psutil_posix' extension 15:13:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:13:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:13:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:13:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:13:49 INFO - Successfully installed psutil 15:13:49 INFO - Cleaning up... 15:13:49 INFO - Return code: 0 15:13:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:49 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:13:49 INFO - Using env: {'DISPLAY': ':0', 15:13:49 INFO - 'HOME': '/home/cltbld', 15:13:49 INFO - 'LANG': 'en_US.UTF-8', 15:13:49 INFO - 'LANGUAGE': 'en_US:en', 15:13:49 INFO - 'LOGNAME': 'cltbld', 15:13:49 INFO - 'MAIL': '/var/mail/cltbld', 15:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:49 INFO - 'MOZ_NO_REMOTE': '1', 15:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:49 INFO - 'NO_EM_RESTART': '1', 15:13:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:49 INFO - 'PWD': '/builds/slave/test', 15:13:49 INFO - 'SHELL': '/bin/bash', 15:13:49 INFO - 'SHLVL': '1', 15:13:49 INFO - 'TERM': 'linux', 15:13:49 INFO - 'TMOUT': '86400', 15:13:49 INFO - 'USER': 'cltbld', 15:13:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:49 INFO - '_': '/tools/buildbot/bin/python'} 15:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:13:52 INFO - Installing collected packages: mozsystemmonitor 15:13:52 INFO - Running setup.py install for mozsystemmonitor 15:13:52 INFO - Successfully installed mozsystemmonitor 15:13:52 INFO - Cleaning up... 15:13:52 INFO - Return code: 0 15:13:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:13:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:13:52 INFO - Using env: {'DISPLAY': ':0', 15:13:52 INFO - 'HOME': '/home/cltbld', 15:13:52 INFO - 'LANG': 'en_US.UTF-8', 15:13:52 INFO - 'LANGUAGE': 'en_US:en', 15:13:52 INFO - 'LOGNAME': 'cltbld', 15:13:52 INFO - 'MAIL': '/var/mail/cltbld', 15:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:52 INFO - 'MOZ_NO_REMOTE': '1', 15:13:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:52 INFO - 'NO_EM_RESTART': '1', 15:13:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:52 INFO - 'PWD': '/builds/slave/test', 15:13:52 INFO - 'SHELL': '/bin/bash', 15:13:52 INFO - 'SHLVL': '1', 15:13:52 INFO - 'TERM': 'linux', 15:13:52 INFO - 'TMOUT': '86400', 15:13:52 INFO - 'USER': 'cltbld', 15:13:52 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:52 INFO - '_': '/tools/buildbot/bin/python'} 15:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:52 INFO - Downloading/unpacking jsonschema==2.5.1 15:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:13:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:54 INFO - Downloading functools32-3.2.3-2.tar.gz 15:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:13:54 INFO - warning: no files found matching '*.txt' 15:13:54 INFO - no previously-included directories found matching 'build' 15:13:54 INFO - no previously-included directories found matching 'dist' 15:13:54 INFO - no previously-included directories found matching '.git*' 15:13:54 INFO - Installing collected packages: jsonschema, functools32 15:13:54 INFO - Running setup.py install for functools32 15:13:54 INFO - warning: no files found matching '*.txt' 15:13:54 INFO - no previously-included directories found matching 'build' 15:13:54 INFO - no previously-included directories found matching 'dist' 15:13:54 INFO - no previously-included directories found matching '.git*' 15:13:54 INFO - Successfully installed jsonschema functools32 15:13:54 INFO - Cleaning up... 15:13:54 INFO - Return code: 0 15:13:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:54 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:13:54 INFO - Using env: {'DISPLAY': ':0', 15:13:54 INFO - 'HOME': '/home/cltbld', 15:13:54 INFO - 'LANG': 'en_US.UTF-8', 15:13:54 INFO - 'LANGUAGE': 'en_US:en', 15:13:54 INFO - 'LOGNAME': 'cltbld', 15:13:54 INFO - 'MAIL': '/var/mail/cltbld', 15:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:54 INFO - 'MOZ_NO_REMOTE': '1', 15:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:54 INFO - 'NO_EM_RESTART': '1', 15:13:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:54 INFO - 'PWD': '/builds/slave/test', 15:13:54 INFO - 'SHELL': '/bin/bash', 15:13:54 INFO - 'SHLVL': '1', 15:13:54 INFO - 'TERM': 'linux', 15:13:54 INFO - 'TMOUT': '86400', 15:13:54 INFO - 'USER': 'cltbld', 15:13:54 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:54 INFO - '_': '/tools/buildbot/bin/python'} 15:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:54 INFO - Downloading/unpacking blobuploader==1.2.4 15:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:56 INFO - Downloading blobuploader-1.2.4.tar.gz 15:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:13:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:13:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:13:57 INFO - Downloading docopt-0.6.1.tar.gz 15:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:13:57 INFO - Installing collected packages: blobuploader, requests, docopt 15:13:57 INFO - Running setup.py install for blobuploader 15:13:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:13:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:13:57 INFO - Running setup.py install for requests 15:13:58 INFO - Running setup.py install for docopt 15:13:58 INFO - Successfully installed blobuploader requests docopt 15:13:58 INFO - Cleaning up... 15:13:58 INFO - Return code: 0 15:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:58 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:13:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:13:58 INFO - Using env: {'DISPLAY': ':0', 15:13:58 INFO - 'HOME': '/home/cltbld', 15:13:58 INFO - 'LANG': 'en_US.UTF-8', 15:13:58 INFO - 'LANGUAGE': 'en_US:en', 15:13:58 INFO - 'LOGNAME': 'cltbld', 15:13:58 INFO - 'MAIL': '/var/mail/cltbld', 15:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:58 INFO - 'MOZ_NO_REMOTE': '1', 15:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:13:58 INFO - 'NO_EM_RESTART': '1', 15:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:58 INFO - 'PWD': '/builds/slave/test', 15:13:58 INFO - 'SHELL': '/bin/bash', 15:13:58 INFO - 'SHLVL': '1', 15:13:58 INFO - 'TERM': 'linux', 15:13:58 INFO - 'TMOUT': '86400', 15:13:58 INFO - 'USER': 'cltbld', 15:13:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:58 INFO - '_': '/tools/buildbot/bin/python'} 15:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:13:58 INFO - Running setup.py (path:/tmp/pip-NC43v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:13:58 INFO - Running setup.py (path:/tmp/pip-JQ9bA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:13:58 INFO - Running setup.py (path:/tmp/pip-RFhZtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:13:58 INFO - Running setup.py (path:/tmp/pip-jfhSar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:13:58 INFO - Running setup.py (path:/tmp/pip-DDh3cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:13:59 INFO - Running setup.py (path:/tmp/pip-UrJfdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:13:59 INFO - Running setup.py (path:/tmp/pip-sKm7h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:13:59 INFO - Running setup.py (path:/tmp/pip-UUSh04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:13:59 INFO - Running setup.py (path:/tmp/pip-8JA_uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:13:59 INFO - Running setup.py (path:/tmp/pip-PkAMcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:13:59 INFO - Running setup.py (path:/tmp/pip-mLuMXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:13:59 INFO - Running setup.py (path:/tmp/pip-8FfVdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:13:59 INFO - Running setup.py (path:/tmp/pip-rm1IZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:13:59 INFO - Running setup.py (path:/tmp/pip-3JbE4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:59 INFO - Running setup.py (path:/tmp/pip-9tKvo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:13:59 INFO - Running setup.py (path:/tmp/pip-8MF59T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:13:59 INFO - Running setup.py (path:/tmp/pip-_laiMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:13:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:13:59 INFO - Running setup.py install for manifestparser 15:13:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Running setup.py install for mozcrash 15:14:00 INFO - Running setup.py install for mozdebug 15:14:00 INFO - Running setup.py install for mozdevice 15:14:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Running setup.py install for mozfile 15:14:00 INFO - Running setup.py install for mozhttpd 15:14:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Running setup.py install for mozinfo 15:14:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Running setup.py install for mozInstall 15:14:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Running setup.py install for mozleak 15:14:00 INFO - Running setup.py install for mozlog 15:14:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Running setup.py install for moznetwork 15:14:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:14:01 INFO - Running setup.py install for mozprocess 15:14:01 INFO - Running setup.py install for mozprofile 15:14:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:14:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:14:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:14:01 INFO - Running setup.py install for mozrunner 15:14:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:14:01 INFO - Running setup.py install for mozscreenshot 15:14:01 INFO - Running setup.py install for moztest 15:14:01 INFO - Running setup.py install for mozversion 15:14:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:14:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:14:01 INFO - Cleaning up... 15:14:01 INFO - Return code: 0 15:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:01 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:14:01 INFO - Using env: {'DISPLAY': ':0', 15:14:01 INFO - 'HOME': '/home/cltbld', 15:14:01 INFO - 'LANG': 'en_US.UTF-8', 15:14:01 INFO - 'LANGUAGE': 'en_US:en', 15:14:01 INFO - 'LOGNAME': 'cltbld', 15:14:01 INFO - 'MAIL': '/var/mail/cltbld', 15:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:01 INFO - 'MOZ_NO_REMOTE': '1', 15:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:01 INFO - 'NO_EM_RESTART': '1', 15:14:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:01 INFO - 'PWD': '/builds/slave/test', 15:14:01 INFO - 'SHELL': '/bin/bash', 15:14:01 INFO - 'SHLVL': '1', 15:14:01 INFO - 'TERM': 'linux', 15:14:01 INFO - 'TMOUT': '86400', 15:14:01 INFO - 'USER': 'cltbld', 15:14:01 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:01 INFO - '_': '/tools/buildbot/bin/python'} 15:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:01 INFO - Running setup.py (path:/tmp/pip-QKjFJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:02 INFO - Running setup.py (path:/tmp/pip-UWZ9vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:02 INFO - Running setup.py (path:/tmp/pip-eV4wno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:02 INFO - Running setup.py (path:/tmp/pip-9KI0NI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:02 INFO - Running setup.py (path:/tmp/pip-HKDVTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:02 INFO - Running setup.py (path:/tmp/pip-1YS89G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:02 INFO - Running setup.py (path:/tmp/pip-Xer5gP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:02 INFO - Running setup.py (path:/tmp/pip-TPzIf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:02 INFO - Running setup.py (path:/tmp/pip-fW9nGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:02 INFO - Running setup.py (path:/tmp/pip-ilPLPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:02 INFO - Running setup.py (path:/tmp/pip-t8WF8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:02 INFO - Running setup.py (path:/tmp/pip-hXigEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:02 INFO - Running setup.py (path:/tmp/pip-DxYemG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:02 INFO - Running setup.py (path:/tmp/pip-caPhCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:02 INFO - Running setup.py (path:/tmp/pip-hYyNS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:03 INFO - Running setup.py (path:/tmp/pip-GDySdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:03 INFO - Running setup.py (path:/tmp/pip-aYV6e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:14:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:05 INFO - Downloading blessings-1.6.tar.gz 15:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:14:05 INFO - Installing collected packages: blessings 15:14:05 INFO - Running setup.py install for blessings 15:14:05 INFO - Successfully installed blessings 15:14:05 INFO - Cleaning up... 15:14:05 INFO - Return code: 0 15:14:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:05 INFO - Reading from file tmpfile_stdout 15:14:05 INFO - Current package versions: 15:14:05 INFO - argparse == 1.2.1 15:14:05 INFO - blessings == 1.6 15:14:05 INFO - blobuploader == 1.2.4 15:14:05 INFO - docopt == 0.6.1 15:14:05 INFO - functools32 == 3.2.3-2 15:14:05 INFO - jsonschema == 2.5.1 15:14:05 INFO - manifestparser == 1.1 15:14:05 INFO - mozInstall == 1.12 15:14:05 INFO - mozcrash == 0.17 15:14:05 INFO - mozdebug == 0.1 15:14:05 INFO - mozdevice == 0.48 15:14:05 INFO - mozfile == 1.2 15:14:05 INFO - mozhttpd == 0.7 15:14:05 INFO - mozinfo == 0.9 15:14:05 INFO - mozleak == 0.1 15:14:05 INFO - mozlog == 3.2 15:14:05 INFO - moznetwork == 0.27 15:14:05 INFO - mozprocess == 0.23 15:14:05 INFO - mozprofile == 0.28 15:14:05 INFO - mozrunner == 6.12 15:14:05 INFO - mozscreenshot == 0.1 15:14:05 INFO - mozsystemmonitor == 0.3 15:14:05 INFO - moztest == 0.7 15:14:05 INFO - mozversion == 1.4 15:14:05 INFO - psutil == 3.1.1 15:14:05 INFO - requests == 1.2.3 15:14:05 INFO - wsgiref == 0.1.2 15:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:05 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:14:05 INFO - Using env: {'DISPLAY': ':0', 15:14:05 INFO - 'HOME': '/home/cltbld', 15:14:05 INFO - 'LANG': 'en_US.UTF-8', 15:14:05 INFO - 'LANGUAGE': 'en_US:en', 15:14:05 INFO - 'LOGNAME': 'cltbld', 15:14:05 INFO - 'MAIL': '/var/mail/cltbld', 15:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:05 INFO - 'MOZ_NO_REMOTE': '1', 15:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:05 INFO - 'NO_EM_RESTART': '1', 15:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:05 INFO - 'PWD': '/builds/slave/test', 15:14:05 INFO - 'SHELL': '/bin/bash', 15:14:05 INFO - 'SHLVL': '1', 15:14:05 INFO - 'TERM': 'linux', 15:14:05 INFO - 'TMOUT': '86400', 15:14:05 INFO - 'USER': 'cltbld', 15:14:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:05 INFO - '_': '/tools/buildbot/bin/python'} 15:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:14:05 INFO - Cleaning up... 15:14:05 INFO - Return code: 0 15:14:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:05 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 0x1f89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:14:05 INFO - Using env: {'DISPLAY': ':0', 15:14:05 INFO - 'HOME': '/home/cltbld', 15:14:05 INFO - 'LANG': 'en_US.UTF-8', 15:14:05 INFO - 'LANGUAGE': 'en_US:en', 15:14:05 INFO - 'LOGNAME': 'cltbld', 15:14:05 INFO - 'MAIL': '/var/mail/cltbld', 15:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:05 INFO - 'MOZ_NO_REMOTE': '1', 15:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:05 INFO - 'NO_EM_RESTART': '1', 15:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:05 INFO - 'PWD': '/builds/slave/test', 15:14:05 INFO - 'SHELL': '/bin/bash', 15:14:05 INFO - 'SHLVL': '1', 15:14:05 INFO - 'TERM': 'linux', 15:14:05 INFO - 'TMOUT': '86400', 15:14:05 INFO - 'USER': 'cltbld', 15:14:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:05 INFO - '_': '/tools/buildbot/bin/python'} 15:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:14:06 INFO - Cleaning up... 15:14:06 INFO - Return code: 0 15:14:06 INFO - Running post-action listener: _resource_record_post_action 15:14:06 INFO - Running post-action listener: _start_resource_monitoring 15:14:06 INFO - Starting resource monitoring. 15:14:06 INFO - ##### 15:14:06 INFO - ##### Finished create-virtualenv step (success) 15:14:06 INFO - ##### 15:14:06 INFO - ##### 15:14:06 INFO - ##### Running install step. 15:14:06 INFO - ##### 15:14:06 INFO - Running pre-action listener: _resource_record_pre_action 15:14:06 INFO - Running main action method: install 15:14:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:06 INFO - Reading from file tmpfile_stdout 15:14:06 INFO - Detecting whether we're running mozinstall >=1.0... 15:14:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:14:06 INFO - Reading from file tmpfile_stdout 15:14:06 INFO - Output received: 15:14:06 INFO - Usage: mozinstall [options] installer 15:14:06 INFO - Options: 15:14:06 INFO - -h, --help show this help message and exit 15:14:06 INFO - -d DEST, --destination=DEST 15:14:06 INFO - Directory to install application into. [default: 15:14:06 INFO - "/builds/slave/test"] 15:14:06 INFO - --app=APP Application being installed. [default: firefox] 15:14:06 INFO - mkdir: /builds/slave/test/build/application 15:14:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:14:22 INFO - Reading from file tmpfile_stdout 15:14:22 INFO - Output received: 15:14:22 INFO - /builds/slave/test/build/application/firefox/firefox 15:14:22 INFO - Running post-action listener: _resource_record_post_action 15:14:22 INFO - ##### 15:14:22 INFO - ##### Finished install step (success) 15:14:22 INFO - ##### 15:14:22 INFO - ##### 15:14:22 INFO - ##### Running run-tests step. 15:14:22 INFO - ##### 15:14:22 INFO - Running pre-action listener: _resource_record_pre_action 15:14:22 INFO - Running main action method: run_tests 15:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:14:22 INFO - Python 2.7.3 15:14:22 INFO - Return code: 0 15:14:22 INFO - grabbing minidump binary from tooltool 15:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:22 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 0x22483b0>, '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 0x22492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2249740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:14:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:14:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:14:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:14:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:14:22 INFO - Return code: 0 15:14:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:14:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:14:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:14:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:14:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:14:22 INFO - Using env: {'DISPLAY': ':0', 15:14:22 INFO - 'HOME': '/home/cltbld', 15:14:22 INFO - 'LANG': 'en_US.UTF-8', 15:14:22 INFO - 'LANGUAGE': 'en_US:en', 15:14:22 INFO - 'LOGNAME': 'cltbld', 15:14:22 INFO - 'MAIL': '/var/mail/cltbld', 15:14:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:14:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:22 INFO - 'MOZ_NO_REMOTE': '1', 15:14:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:22 INFO - 'NO_EM_RESTART': '1', 15:14:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:22 INFO - 'PWD': '/builds/slave/test', 15:14:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:14:22 INFO - 'SHELL': '/bin/bash', 15:14:22 INFO - 'SHLVL': '1', 15:14:22 INFO - 'TERM': 'linux', 15:14:22 INFO - 'TMOUT': '86400', 15:14:22 INFO - 'USER': 'cltbld', 15:14:22 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592', 15:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:22 INFO - '_': '/tools/buildbot/bin/python'} 15:14:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:14:22 INFO - mozversion application_buildid: 20160711135528 15:14:22 INFO - mozversion application_changeset: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 15:14:22 INFO - mozversion application_display_name: Nightly 15:14:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:14:22 INFO - mozversion application_name: Firefox 15:14:22 INFO - mozversion application_remotingname: firefox 15:14:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:14:22 INFO - mozversion application_vendor: Mozilla 15:14:22 INFO - mozversion application_version: 50.0a1 15:14:22 INFO - mozversion platform_buildid: 20160711135528 15:14:22 INFO - mozversion platform_changeset: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 15:14:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:14:22 INFO - mozversion platform_version: 50.0a1 15:14:22 INFO - using testdate: 1468275262 15:14:22 INFO - actual date: 1468275262 15:14:22 INFO - starting webserver on 'localhost:53874' 15:14:22 INFO - SUITE-START | Running 2 tests 15:14:22 INFO - TEST-START | dromaeo_css 15:14:22 INFO - operating with platform_type : linux_ 15:14:22 INFO - Initialising browser for dromaeo_css test... 15:14:22 INFO - TEST-INFO | started process 30644 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPjfNl_/profile http://localhost:53874/getInfo.html) 15:14:27 INFO - PROCESS | 30644 | __metrics Screen width/height:1600/1200 15:14:27 INFO - PROCESS | 30644 | colorDepth:24 15:14:27 INFO - PROCESS | 30644 | Browser inner width/height: 1024/697 15:14:27 INFO - PROCESS | 30644 | __metrics 15:14:27 INFO - PROCESS | 30644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:14:27 INFO - PROCESS | 30644 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:14:28 INFO - TEST-INFO | 30644: exit 0 15:14:28 INFO - Browser initialized. 15:14:28 INFO - Running cycle 1/1 for dromaeo_css test... 15:14:28 INFO - TEST-INFO | started process 30723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPjfNl_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:40 INFO - PROCESS | 30723 | RSS: Main: 159215616 15:14:40 INFO - PROCESS | 30723 | 15:15:31 INFO - PROCESS | 30723 | 1468275331531 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:17:44 INFO - PROCESS | 30723 | Cycle 1(1): loaded http://localhost:53874/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53874/tests/dromaeo/cssquery-ext.html) 15:17:45 INFO - PROCESS | 30723 | RSS: Main: 274849792 15:17:45 INFO - PROCESS | 30723 | 15:20:48 INFO - PROCESS | 30723 | Cycle 1(1): loaded http://localhost:53874/tests/dromaeo/cssquery-ext.html (next: http://localhost:53874/tests/dromaeo/cssquery-jquery.html) 15:20:48 INFO - PROCESS | 30723 | RSS: Main: 236769280 15:20:48 INFO - PROCESS | 30723 | 15:23:51 INFO - PROCESS | 30723 | Cycle 1(1): loaded http://localhost:53874/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53874/tests/dromaeo/cssquery-mootools.html) 15:23:51 INFO - PROCESS | 30723 | RSS: Main: 234463232 15:23:51 INFO - PROCESS | 30723 | 15:26:53 INFO - PROCESS | 30723 | Cycle 1(1): loaded http://localhost:53874/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53874/tests/dromaeo/cssquery-prototype.html) 15:26:54 INFO - PROCESS | 30723 | RSS: Main: 235757568 15:26:54 INFO - PROCESS | 30723 | 15:29:57 INFO - PROCESS | 30723 | Cycle 1(1): loaded http://localhost:53874/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53874/tests/dromaeo/cssquery-yui.html) 15:29:58 INFO - PROCESS | 30723 | RSS: Main: 210866176 15:29:58 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | Cycle 1(1): loaded http://localhost:53874/tests/dromaeo/cssquery-yui.html (next: http://localhost:53874/tests/dromaeo/cssquery-dojo.html) 15:33:01 INFO - PROCESS | 30723 | RSS: Main: 277438464 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | __start_tp_report 15:33:01 INFO - PROCESS | 30723 | _x_x_mozilla_page_load 15:33:01 INFO - PROCESS | 30723 | _x_x_mozilla_page_load_details 15:33:01 INFO - PROCESS | 30723 | |i|pagename|runs| 15:33:01 INFO - PROCESS | 30723 | |0;dojo.html;2585.392432778297;2631.552636051871;2654.283343497256;2658.0696756135353;2676.772474339681;4557.817687292512;4662;4718.032803275329;4723.362346083282;4770.570648641621;4626.930596041054;4638.373819534362;4669.6731228814;4722.669413141081;4735.171345014626;4082.203970225807;4219;4299.828006879721;4328.63206627437;4469.709468884512;4374.671899607526;4404.647628189737;4425.31407631816;4432.556744325558;4459.331100334959;4204.369344598319;4263.424437700902;4320.1575692739925;4352.934705979413;4397.758123303217;4292.1630282095;4294.935575966363;4304.031592891605;4411.09572537629;4450.065486247891;4321.546237645033;4346.043870348518;4355.1725172217175;4399.186150562125;4401.075774087445;4612.976935115303;4673.275642275453;4723.36234608327;4738.47876733559;4776.259679749644;5115;5121.078205922932;5136.203888397305;5138.254953031789;5139.254808052811;5126.948730512684;5135.178371460548;5150.356205474316;5156.994386094713;5162.199859021857;4557.043020965568;4659.5806377425515;4701.64737644678;4714.292856071548;4732.455767586737;5152.175651895754;5167.354080739908;5167.612429067835;5225.843224703265;5235.790568377299;4954.628402869727;4954.851354459372;4987.10232158215;5003.749812509433;5011.173156429221;4876.195427754381;4999.775010124553;5062.139436295838;5072.746362681851;5084.084864724311;4996.07572599063;5031.471695471922;5035.370578677665;5035.672681275779;5082.2122571001555;4774.665773395829;4998.90002199954;5008.048470790538;5008.974955125201;5011.09800235961;4185.351270553069;4211.7472951623;4212.68404869636;4256.808443620044;4306.461692288464;3706.9073273168387;3736.140687641803;3738.36447803874;3773.01901505605;3774.5281839770028;4273.145370925828;4282.229198744194;4283.22901877665;4325.2214601371425;4333.111712099012;4900.019996000814;4922.778474968635;4990.101781679333;5039.722815245197;5058.620603454682;4294.3558466230315;4309.202797482413;4319.416878721395;4320.805563749576;4362.585554372329;4534.88662783433;4535.183666939983;4537.1833070047705;4538.205813982539;4589.632829373642;4601.240795268744;4697.295405689174;4749.548792864673;4758.547937945889;4765.523447655207;5064.924026139537;5068.29043933857;5082.923756143735;5083.491650835035;5138.845834624967;4520.660950428797;4719.244920812654;4751.524847515221;4760.738159401266;4808.2787581863;4932.679375840702;4954.900901982012;4984.352034235526;5001.924971125363;5004.674696144739;4985.52637499437;5012.223105418666;5020.297158397754;5021.49785021495;5068.442471328077;4733.786979585858;4895.510448955219;4900.8774780630765;4912.091263116335;4953.257011448168;4928.728919909439;4954.1825598775795;4979.800807967786;4982.377202849644;5024.4473107957365;4843.128236917248;4852.2479015752615;4858.368412106403;4908.92636610444;4914.827981020647;4810.63920205979;4840.951590484191;4844;4996.725180115129;5035.0685123252315;4646.883827904329;4688.179568575554;4691.483936767021;4723.527647235249;4743.715377077248 15:33:01 INFO - PROCESS | 30723 | |1;ext.html;540.9168305095906;584.4389386189258;590.0087852407954;591.2254946020711;601.0503404620698;27176.59235111475;27703.584446233297;29797.106086817403;40811.79594102025;42014.579854201445;41361.17277654445;42628.36057459133;42720;42727.572724272824;46657.300140497835;7746.845063098749;7869.763907082796;7892.763217103496;7900.644470998819;7901.841963160733;7467.327940485356;7780;10200;10490.895091049106;10494.422806745626;10094.041066098709;10503.159747220205;10510.632127875526;10520;10522.105621022223;10381.948090259539;10428.89571104287;10463.424511651856;10468.581256749721;10469.947650261738;10279.383237005728;10359.171266298677;10367.688969330931;10369.170466362673;10374.948125259401;40593;40874.79562602168;40907.795461022804;41013.384799228035;41078.79460602708;3089.5365695145683;3255.7232635226032;3265.673432656739;3607.8556857725657;3647.708183345326;31137;31421;31806.204844879063;31847.681523184703;31877;3140.764442666786;3180.236743181643;3193.1218914798615;3196.089678046839;3223.7098661120613;276892;277164;278383;278991;279164;59117.704411477665;59923.101153482734;59935.99999999913;59949.70025149934;60051.699741501885;54391.18413223805;63251.68374158192;63457.682711586145;63516;63520.364796351445;13016.609501714964;13391.665208369868;13412.591677873877;13417.865821341662;13420.328983550979;8867.733967980841;10303.536340864679;10373.948130259301;10425.165986721044;10431.634892778717;18291;18917.432477025435;18999.905000475184;19025.904870475835;19086.236550538102;7141.607211603412;7230.313120253568;7248.238934911758;7332.303431174137;7338.633068346561;794.6225542867207;875.9255463285565;927.9089897366143;940.0270719804969;1008.7578981044496;28220.576691349645;28551.71448285532;28556.714432855406;29036.7096329034;29101.272468188465;891.3136884598953;958.2573505533128;963.8554216867526;988.6342053440274;1002.368507840056;14193.361298741582;14231.857681423053;14317.284135793168;14421.855781442051;14622.926885365718;368439;369228;374253;376524;376945;50483.49516504861;50497;50792.49207507951;50901.49098508967;50926.49073509217;1088.276296262992;1158.901493373056;1182.9645110646695;1196.0371900620084;1214.2957084890918;28355;29322.706772932852;29377;29666.703332966394;30213.848930756085;41152.794236028625;41839.7908010458;41890.37164442597;41928.3710744333;41941.58058419377;237412;240977;241278;241336;242918;32209.51685724669;36686.798792072565;37477.43783843299;38236.80881595686;42092.78953605212;41754.79122604368;41813;42061.78969105135;42338.57661423346;42566;36036.63963360333;36357.27285454329;36553.63446365502;36655.26689466142;37022.8148859254;35402.46896296506;36563.63436365602;37110.62889371072;37187.81406092952;37256;32364.83817580897;32644.34711305808;32650.34699306048;32831.50752738663;32899;30843;34045.829770850985;36178.8191059043;36213.63786362208;36522;25513.489730205663;25965.740342597088;26110.86944565265;26148.8692556536;26569 15:33:01 INFO - PROCESS | 30723 | |2;jquery.html;343.47497112819065;367.13173345038973;371.6655010490559;373.3764613096129;386.67712460095834;13106.868931310684;15128.016678915863;17320.307187712475;17379.391721289758;17420.738688919675;16954.40659576915;17763.289468421248;17981.550461238476;18032.27870885163;18046;5265.921011184826;5439.5920305977015;5477.616566840323;5479.342478902538;5481.492589537889;3738.495303134083;5349.5720342372715;6013.218281623383;6295.685215739218;6350.777722779703;6165.660888651122;6405.967970160166;6444.548881578291;6444.581102228341;6453.741850325981;6298.0552917062805;6314.842128946813;6317.873642527146;6326.999999999977;6364.49084073273;3432.2792213635166;5220.530152286275;5516.862078448031;5522.282103326542;5568.49883510482;21360.145594176214;21740.456488587755;21986.340409787732;22041.559168816533;22042.559148816934;1928.2479832865195;1942.6988816733428;1976.8912709800954;1993.5215548268457;2002.7196192533056;13372.665683357896;13630.591082267549;13764.724705505934;13856.445742170301;14103.929480352635;1603.4708546179734;1669.7745804316505;1731.6709825133184;1737.6264103217743;1766.97349539757;45457;64026;65441;70920;72838.2716172842;9904.55429505674;10128.746781330527;10132.645357412455;10331.468790146311;10336.431496267778;13020.934895325463;13070.477180912658;13117.60647180586;13277.601671949857;13536;7102.573845569283;7290.015847860577;7311.378532824663;7357.742479013209;7388.926110738931;6917.273686262971;6944.930550694529;7050.365467107985;7062.92937070633;7074.115735533059;6255.343188965184;6321.55749097568;6348.873022539523;6361.409647588103;6464.838379040561;1716.3048965169125;1752.8685348598651;1777.4667599720292;1798.4694515118072;1813.7279408088627;786.3591173193829;820.6717313074698;827.3050637464559;848.4315508609124;849.180540778152;17372;18317.17572709231;18330.63338733218;18469.445916622524;18628.72056919147;842.2546046748549;869.4174902815129;877.4688169144756;894.7584152278848;930.5765876526165;9839.114479696862;10082.647107351355;10288.228382871315;10470.895291046992;10549.789004220027;384696;387737;388231;388367;395724;50484.747576261885;52409.2138617921;62278;88321;90283.54858225667;909.3316412436853;910.2354022620898;934.0472717827913;946.5929650250458;949.8100379924043;12389.75220495603;15784.973976691479;15954.521364359089;16135.112568808829;17142.057186854843;26981;28074.578881317204;36382.726604568714;38594.421083683206;39239.60760392359;47796;48594.51405486041;52657.21014184867;52952;53294.200586992;6536;6777.491688123312;6825.215100263533;7005.894911576433;7747.92252077472;6053.3946605339115;8243.917560824555;8297.502149871027;9346.01866804003;11345.205835591429;9627.614895404105;9748.366356187109;9936.056074672895;10002.599376149818;10122.848157277795;4505.7296562206375;5448.509634132947;9780.41317520951;10274.383536987805;10357.4266877927;10017.348872323275;10039.498025098861;10109.848352274576;10205.591776328863;10416.166706663296;6979.40675042627;7029.894551581628;7030.3321184487395;7166.104236970379;7222.241664625139;9891.554880030271;9994.950025249827;10070.597176112873;10183.287169898036;10276.588936442758 15:33:01 INFO - PROCESS | 30723 | |3;mootools.html;1341.885939695126;1381.0608786025502;1398.5454727213653;1617.555172327609;1642.1050527462533;7132.821679458009;7545.7358992435275;7590.620468976543;7609.086909570852;7619.809504762384;6412.230532336126;7053.15362156542;7265.455090868193;7303.598302093383;7424.146223184335;2131.2434085899476;2153.9569208615817;2160.5354848707525;2163.145557504785;2164.512984578473;2134.4770531219874;2144.914203431869;2183.6942828004094;2185.5410363823617;2187.037703410502;1829.0488945748202;2092.9058192381303;2150.0862133593237;2152.6448136057534;2180.934571962836;2150.40863762465;2151.9354419367446;2170.9240176593744;2173.7717539658347;2205.6801763744165;2146.9785302146934;2150.1399440224036;2151.2900742754855;2153.23560136151;2157.6224160771803;7563.508371955805;7622.237776222389;7627.2372762723835;7629.961850190769;7634.6182690865235;4292.785360731951;4451.577100175478;4454.265046267362;4455.22033644111;4457;2670.6661667291587;2776.1393967870026;2793.0782841662203;2793.483205606969;2797;839.4627438439404;867.7136544940155;872.3021582733787;880.5773228850188;884.6417201033591;9995.600175992951;10013.098821106063;10031.498425078717;10054.044865737817;10066.094051535398;3948.5853985331128;4026.798660066985;4154.979225103855;4161.271777438936;4167.74993500391;8429.241368276884;8487.278581320534;8489.830203395897;8491.61787719554;8499.022612399529;4464.508904020555;4468.396766436555;4470.664700147502;4489.304157855538;4493.123840851068;3985.9202815943518;4033.4554835097474;4040.8383664653675;4042.312806822847;4042.3532234842282;3503.649635036527;3558.1104723819044;3619.131408462003;3626.6192049834917;3632.146445585285;1005.6932635546228;1090.4602221900052;1146.4439746722833;1151.988480115205;1155.589765633205;646.683125268615;744.4639859301294;814.8248126652799;834;842.2588122452325;4721.433427988663;4801.903961920742;4855.757212139379;4871;4891.413030436299;791.0546896458792;840.6091167607092;849.5539841583095;852.497026754218;857.8841856349438;3343.297907439416;3351.815650139266;3386.72906167506;3421.7775844570024;3440.0539851540525;8642.22220000203;9055.637774489078;9147.817043659223;9167.12912273333;9170.358074934691;7887.723929662549;9016.774580635536;9037.186653201243;9108.726738197865;9113.589888455035;6476.773312934119;6771.424428923498;6773.8983915241315;6923.826904327331;6941.236463988957;4842.927356089591;4942.825156930939;4943.283223932582;4975.5522003019905;4997.300377947017;8271.95864020676;8388.245057944814;8496.447730897471;8499.617517211616;8500.36247281444;8060.919390806017;8403.537805420761;8566.957165214135;8651.264642505206;8652.740417787476;6460.741570337133;6586.80239592813;6596.208454985433;6602.801915942529;6612.603243805387;6915.792526224222;6971.058907047483;7013.263607321362;7111.288871112847;7125.501214915113;4736.8578942631775;6174.598651087665;6198.008318669172;6266.937330626635;6378.202724659418;6348.873022539616;6440.516961227833;6464.191976003;6486.2216534016225;6575.80272591823;5643.887122257614;5655.179998900218;5727;5772.711364431678;5833.4749872511675;4174.853880114181;4240.681948853787;4329.826806927687;4359.978200108979;4407.052483715888;4410.536893626123;4462.598366147063;4481.887952801206;4549.545045495424;4552.7723613819835 15:33:01 INFO - PROCESS | 30723 | |4;prototype.html;291.9357741296915;297.23462085130785;298.5223642172523;305.92504836315106;306.79291478252173;4840.467548569654;4888.633352498568;4910.508949105088;4926.137925862978;4932.358793356863;4802.08760335536;4814.855554333367;4853.805847766094;4860.90629608338;4868.805247790093;3651.434027725707;3727.5154229950067;3777.3200823851694;3795.2978698940783;3811.1615444602144;3751.23099764549;3783.3920583751933;3786.318462676717;3787.602301758317;3821.7706937583835;3778.716596255278;3780.527434070741;3783.8864834054884;3788.7726736395903;3809.961900381002;3578.7137029037613;3681.4109742441346;3767.830447629863;3775.6413140751583;3791.9431208531896;3339.5491608632765;3343.1307859956587;3348.547946027304;3352.547406100194;3357.9496307555405;4730.195866702635;4732.195526760424;4738.005018946025;4753.643476739224;4758.78585463655;4218.367244913257;4290;4338.58783415575;4344;4360.280553708634;4927.61873373978;4964.106460837048;5021.723805190713;5022.347094877679;5042.369703787027;3622.1125824173246;3632.5277713897026;3661.176235347052;3678.742488025839;3692.2615476904457;5146.897062058775;5257.211418287249;5280.577553795687;5283.788648454057;5284.894302113936;3657.213699054716;3659.4144936809985;3659.652333028358;3677.3748462761396;3686.562240726119;3726.8136593170775;3730.7948062856262;3739;3739.3456145174487;3741.3265612190066;4083.9183216335505;4273.508546517191;4275.828966841292;4276.828926842892;4287.421198138274;5008.5993120550265;5018.924716129261;5023.2214006829;5042.823501177441;5043.420006699276;1261.993690031544;1279.2004996876951;1286.1125823181974;1287.3241548187088;1288.5103660608909;809.8987626546682;837.6146972392768;854.7734850264685;855.3883972959239;863.0721973878107;4316.179925814085;4330.631896288851;4350.891227719269;4353.455818022747;4355.5208927018;5063.3417655704525;5076.6192535559985;5081.796728130834;5084.6440749147205;5099.745012749422;4303.698741088157;4317.244482215599;4326.015049615755;4339.717918335298;4342.088161486124;2615.5030544196543;2756.490049340878;2758.903438379647;2760;2760.323720688444;4840.709557426566;4853.830115945995;4861.596882182486;4862.440819305754;4879.4388645305535;5080.872978175576;5294.841154765363;5319.308489896289;5334.51989320963;5343.198520221998;4844.297576851407;4844.515548445198;4850.563449289581;4896.4124305083615;4906;5145.9228111579105;5184.740762961912;5189.76646050921;5193.298904647975;5199.896002080013;4937.5927860561005;5121.79512819498;5122.769475373542;5130.384353877558;5132.1267737274875;4876.195427754381;4880.779805048738;4888.633352498506;4888.877778055577;4899.1426500363;3720.2187540616774;3730.384486559687;3767.9434808478445;3774.75464094833;3781.3760729479327;4923.901521969469;5244.947550524446;5260.263563101245;5269.525742683177;5275.155975043975;4966.155753521838;5007.449180590059;5030.62270329734;5039.319691841701;5049.419316778471;5013.846815232444;5015.22264049073;5056.595472362275;5064.139096353555;5071.290019397214;4801.807927682854;4935.802567897387;4952.4304704959395;4954.5540901319055;4958.603311734981;4831.661783675249;4953.405591329064;5071.594272458121;5085.753990272407;5094.2613321068975;4885.877853053702;4909.9018019640125;4940.92588611164;4950.727709975842;4955.900881982412 15:33:01 INFO - PROCESS | 30723 | |5;yui.html;654.6039646014161;660.9537332386733;662.6653539962318;664.4252721395991;730.9729829589429;11824.290542567453;12212.511499540007;12267.815982760243;12304.692382690437;12305;11799.823002654966;11913.404329783498;11918.404079796019;11942.402879855994;11947.223430477014;624.5472032776246;749.048708140661;759.6163937211701;774.4075782026754;779.7193010516238;755.5315704263364;760.5664771080486;761.1817296406385;761.603965937712;763.1795819494039;828.8093738440162;829.1004260377467;830.4270053662983;830.4560512864053;832.1262674192075;646.4279112985018;784.8783438566974;811.0146172400562;813.0649752784286;820.9671613135467;594.8839976204641;597.4742226840352;597.5578072226565;597.8953683105439;598.5600583571072;12069.094817888606;12078.214916030518;12103.697407564794;12108.455119519644;12114.575989840314;12401.62795116148;12747.936260318638;12755.234685918875;13014.284214368206;13040.413181406764;11574.6527604172;11752.647420577396;11811.350375729331;11872.88127118735;12151.270923744603;4098.262312783698;4130.648894843942;4158.563350848148;4160.667146628292;4160.979195104035;59249;60602.69698651523;60741.088883666336;60887.391126088616;61306.38693613051;9560.760980975392;9935.105840474247;10302.536385862653;10426.061654451136;10482.947585262025;19616.11727472267;19871.205151794056;19974.800251997585;19995.900020499805;20015.09932053061;6773.593584384953;7141.500094993405;7165.426765858719;7203.207647158808;7209.243029481934;3889.3971434427144;3985.4818873546833;4008.0781420272915;4017.959820401817;4026.798660066985;2824.237455886899;2939.617849679528;2940.088572542476;2940.6324209473814;2942.411517696469;496.9478204788445;497.2640492071754;503.66506273327826;504.634140248317;509.18784538660776;455.562659846551;455.71062375391864;512.5720023780185;554.9500544950973;558.7539786276626;8375.790605234795;8578.828423431622;8613.870791938127;8759.255463285692;8829.402128178792;521.7417378397718;551.6166264446172;596.4304089594427;599.12228585123;608.0878681977034;4173.227952828736;4326.264535029052;4334.696571239983;4381.123775244963;4398.846040388571;94961.05038949661;101925.49037254766;102283;106232;106811.46594267135;68905.65547172232;71195;92995.53502232446;96598.51700741593;97155.51422242985;70311.2968870315;70764;74402.62798685972;74810.625946871;75986.9999999989;8719.346049046473;9149.77125571866;9225.21585665226;9634.807303853744;9654.75863103428;42642.78678606711;45328.09343813171;45607;45819.541804581524;46669.06661866812;60057.69971150116;66735;66842.33157668555;66852.665736671;66899.66550167218;11589.768204635691;11735.119866009913;11850.170488065753;12208.633740987785;12253;13153.868461315647;13285.269310188032;13576.389062492013;13685.247311397969;14184.574462766133;11423.257488263236;11805.291682498733;12210.328431936328;12239.877601223874;12260.632181034583;12362.196457230251;13023.934880325538;13111;13122.540711075448;13146.34268286601;11348.943255283672;11456.770864582828;11699.181057326246;11794.646160615195;12368.44342004594;10329.522528580976;10672.62645807393;10736.141108711128;10771.569137234423;10829.729256768644;10553.18810779677;10910.890891090987;10984.780304394028;11071.335719856834;11085.612003579836 15:33:01 INFO - PROCESS | 30723 | __end_tp_report 15:33:01 INFO - PROCESS | 30723 | __start_cc_report 15:33:01 INFO - PROCESS | 30723 | _x_x_mozilla_cycle_collect,1131 15:33:01 INFO - PROCESS | 30723 | __end_cc_report 15:33:01 INFO - PROCESS | 30723 | __startTimestamp1468276381589__endTimestamp 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | ------- Summary: start ------- 15:33:01 INFO - PROCESS | 30723 | Number of tests: 6 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | [#0] dojo.html Cycles:165 Average:4652.86 Median:4743.72 stddev:492.07 (10.4%) stddev-sans-first:466.08 15:33:01 INFO - PROCESS | 30723 | Values: 2585.4 2631.6 2654.3 2658.1 2676.8 4557.8 4662.0 4718.0 4723.4 4770.6 4626.9 4638.4 4669.7 4722.7 4735.2 4082.2 4219.0 4299.8 4328.6 4469.7 4374.7 4404.6 4425.3 4432.6 4459.3 4204.4 4263.4 4320.2 4352.9 4397.8 4292.2 4294.9 4304.0 4411.1 4450.1 4321.5 4346.0 4355.2 4399.2 4401.1 4613.0 4673.3 4723.4 4738.5 4776.3 5115.0 5121.1 5136.2 5138.3 5139.3 5126.9 5135.2 5150.4 5157.0 5162.2 4557.0 4659.6 4701.6 4714.3 4732.5 5152.2 5167.4 5167.6 5225.8 5235.8 4954.6 4954.9 4987.1 5003.7 5011.2 4876.2 4999.8 5062.1 5072.7 5084.1 4996.1 5031.5 5035.4 5035.7 5082.2 4774.7 4998.9 5008.0 5009.0 5011.1 4185.4 4211.7 4212.7 4256.8 4306.5 3706.9 3736.1 3738.4 3773.0 3774.5 4273.1 4282.2 4283.2 4325.2 4333.1 4900.0 4922.8 4990.1 5039.7 5058.6 4294.4 4309.2 4319.4 4320.8 4362.6 4534.9 4535.2 4537.2 4538.2 4589.6 4601.2 4697.3 4749.5 4758.5 4765.5 5064.9 5068.3 5082.9 5083.5 5138.8 4520.7 4719.2 4751.5 4760.7 4808.3 4932.7 4954.9 4984.4 5001.9 5004.7 4985.5 5012.2 5020.3 5021.5 5068.4 4733.8 4895.5 4900.9 4912.1 4953.3 4928.7 4954.2 4979.8 4982.4 5024.4 4843.1 4852.2 4858.4 4908.9 4914.8 4810.6 4841.0 4844.0 4996.7 5035.1 4646.9 4688.2 4691.5 4723.5 4743.7 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | [#1] ext.html Cycles:180 Average:46716.85 Median:32865.25 stddev:79325.79 (241.4%) stddev-sans-first:79472.55 15:33:01 INFO - PROCESS | 30723 | Values: 540.9 584.4 590.0 591.2 601.1 27176.6 27703.6 29797.1 40811.8 42014.6 41361.2 42628.4 42720.0 42727.6 46657.3 7746.8 7869.8 7892.8 7900.6 7901.8 7467.3 7780.0 10200.0 10490.9 10494.4 10094.0 10503.2 10510.6 10520.0 10522.1 10381.9 10428.9 10463.4 10468.6 10469.9 10279.4 10359.2 10367.7 10369.2 10374.9 40593.0 40874.8 40907.8 41013.4 41078.8 3089.5 3255.7 3265.7 3607.9 3647.7 31137.0 31421.0 31806.2 31847.7 31877.0 3140.8 3180.2 3193.1 3196.1 3223.7 276892.0 277164.0 278383.0 278991.0 279164.0 59117.7 59923.1 59936.0 59949.7 60051.7 54391.2 63251.7 63457.7 63516.0 63520.4 13016.6 13391.7 13412.6 13417.9 13420.3 8867.7 10303.5 10373.9 10425.2 10431.6 18291.0 18917.4 18999.9 19025.9 19086.2 7141.6 7230.3 7248.2 7332.3 7338.6 794.6 875.9 927.9 940.0 1008.8 28220.6 28551.7 28556.7 29036.7 29101.3 891.3 958.3 963.9 988.6 1002.4 14193.4 14231.9 14317.3 14421.9 14622.9 368439.0 369228.0 374253.0 376524.0 376945.0 50483.5 50497.0 50792.5 50901.5 50926.5 1088.3 1158.9 1183.0 1196.0 1214.3 28355.0 29322.7 29377.0 29666.7 30213.8 41152.8 41839.8 41890.4 41928.4 41941.6 237412.0 240977.0 241278.0 241336.0 242918.0 32209.5 36686.8 37477.4 38236.8 42092.8 41754.8 41813.0 42061.8 42338.6 42566.0 36036.6 36357.3 36553.6 36655.3 37022.8 35402.5 36563.6 37110.6 37187.8 37256.0 32364.8 32644.3 32650.3 32831.5 32899.0 30843.0 34045.8 36178.8 36213.6 36522.0 25513.5 25965.7 26110.9 26148.9 26569.0 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | [#2] jquery.html Cycles:180 Average:23940.18 Median:48195.26 stddev:64015.10 (132.8%) stddev-sans-first:64170.16 15:33:01 INFO - PROCESS | 30723 | Values: 343.5 367.1 371.7 373.4 386.7 13106.9 15128.0 17320.3 17379.4 17420.7 16954.4 17763.3 17981.6 18032.3 18046.0 5265.9 5439.6 5477.6 5479.3 5481.5 3738.5 5349.6 6013.2 6295.7 6350.8 6165.7 6406.0 6444.5 6444.6 6453.7 6298.1 6314.8 6317.9 6327.0 6364.5 3432.3 5220.5 5516.9 5522.3 5568.5 21360.1 21740.5 21986.3 22041.6 22042.6 1928.2 1942.7 1976.9 1993.5 2002.7 13372.7 13630.6 13764.7 13856.4 14103.9 1603.5 1669.8 1731.7 1737.6 1767.0 45457.0 64026.0 65441.0 70920.0 72838.3 9904.6 10128.7 10132.6 10331.5 10336.4 13020.9 13070.5 13117.6 13277.6 13536.0 7102.6 7290.0 7311.4 7357.7 7388.9 6917.3 6944.9 7050.4 7062.9 7074.1 6255.3 6321.6 6348.9 6361.4 6464.8 1716.3 1752.9 1777.5 1798.5 1813.7 786.4 820.7 827.3 848.4 849.2 17372.0 18317.2 18330.6 18469.4 18628.7 842.3 869.4 877.5 894.8 930.6 9839.1 10082.6 10288.2 10470.9 10549.8 384696.0 387737.0 388231.0 388367.0 395724.0 50484.7 52409.2 62278.0 88321.0 90283.5 909.3 910.2 934.0 946.6 949.8 12389.8 15785.0 15954.5 16135.1 17142.1 26981.0 28074.6 36382.7 38594.4 39239.6 47796.0 48594.5 52657.2 52952.0 53294.2 6536.0 6777.5 6825.2 7005.9 7747.9 6053.4 8243.9 8297.5 9346.0 11345.2 9627.6 9748.4 9936.1 10002.6 10122.8 4505.7 5448.5 9780.4 10274.4 10357.4 10017.3 10039.5 10109.8 10205.6 10416.2 6979.4 7029.9 7030.3 7166.1 7222.2 9891.6 9995.0 10070.6 10183.3 10276.6 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | [#3] mootools.html Cycles:180 Average:4864.56 Median:4917.12 stddev:2677.12 (54.4%) stddev-sans-first:2671.54 15:33:01 INFO - PROCESS | 30723 | Values: 1341.9 1381.1 1398.5 1617.6 1642.1 7132.8 7545.7 7590.6 7609.1 7619.8 6412.2 7053.2 7265.5 7303.6 7424.1 2131.2 2154.0 2160.5 2163.1 2164.5 2134.5 2144.9 2183.7 2185.5 2187.0 1829.0 2092.9 2150.1 2152.6 2180.9 2150.4 2151.9 2170.9 2173.8 2205.7 2147.0 2150.1 2151.3 2153.2 2157.6 7563.5 7622.2 7627.2 7630.0 7634.6 4292.8 4451.6 4454.3 4455.2 4457.0 2670.7 2776.1 2793.1 2793.5 2797.0 839.5 867.7 872.3 880.6 884.6 9995.6 10013.1 10031.5 10054.0 10066.1 3948.6 4026.8 4155.0 4161.3 4167.7 8429.2 8487.3 8489.8 8491.6 8499.0 4464.5 4468.4 4470.7 4489.3 4493.1 3985.9 4033.5 4040.8 4042.3 4042.4 3503.6 3558.1 3619.1 3626.6 3632.1 1005.7 1090.5 1146.4 1152.0 1155.6 646.7 744.5 814.8 834.0 842.3 4721.4 4801.9 4855.8 4871.0 4891.4 791.1 840.6 849.6 852.5 857.9 3343.3 3351.8 3386.7 3421.8 3440.1 8642.2 9055.6 9147.8 9167.1 9170.4 7887.7 9016.8 9037.2 9108.7 9113.6 6476.8 6771.4 6773.9 6923.8 6941.2 4842.9 4942.8 4943.3 4975.6 4997.3 8272.0 8388.2 8496.4 8499.6 8500.4 8060.9 8403.5 8567.0 8651.3 8652.7 6460.7 6586.8 6596.2 6602.8 6612.6 6915.8 6971.1 7013.3 7111.3 7125.5 4736.9 6174.6 6198.0 6266.9 6378.2 6348.9 6440.5 6464.2 6486.2 6575.8 5643.9 5655.2 5727.0 5772.7 5833.5 4174.9 4240.7 4329.8 4360.0 4407.1 4410.5 4462.6 4481.9 4549.5 4552.8 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | [#4] prototype.html Cycles:180 Average:4185.96 Median:4842.50 stddev:1210.96 (25.0%) stddev-sans-first:1178.56 15:33:01 INFO - PROCESS | 30723 | Values: 291.9 297.2 298.5 305.9 306.8 4840.5 4888.6 4910.5 4926.1 4932.4 4802.1 4814.9 4853.8 4860.9 4868.8 3651.4 3727.5 3777.3 3795.3 3811.2 3751.2 3783.4 3786.3 3787.6 3821.8 3778.7 3780.5 3783.9 3788.8 3810.0 3578.7 3681.4 3767.8 3775.6 3791.9 3339.5 3343.1 3348.5 3352.5 3357.9 4730.2 4732.2 4738.0 4753.6 4758.8 4218.4 4290.0 4338.6 4344.0 4360.3 4927.6 4964.1 5021.7 5022.3 5042.4 3622.1 3632.5 3661.2 3678.7 3692.3 5146.9 5257.2 5280.6 5283.8 5284.9 3657.2 3659.4 3659.7 3677.4 3686.6 3726.8 3730.8 3739.0 3739.3 3741.3 4083.9 4273.5 4275.8 4276.8 4287.4 5008.6 5018.9 5023.2 5042.8 5043.4 1262.0 1279.2 1286.1 1287.3 1288.5 809.9 837.6 854.8 855.4 863.1 4316.2 4330.6 4350.9 4353.5 4355.5 5063.3 5076.6 5081.8 5084.6 5099.7 4303.7 4317.2 4326.0 4339.7 4342.1 2615.5 2756.5 2758.9 2760.0 2760.3 4840.7 4853.8 4861.6 4862.4 4879.4 5080.9 5294.8 5319.3 5334.5 5343.2 4844.3 4844.5 4850.6 4896.4 4906.0 5145.9 5184.7 5189.8 5193.3 5199.9 4937.6 5121.8 5122.8 5130.4 5132.1 4876.2 4880.8 4888.6 4888.9 4899.1 3720.2 3730.4 3767.9 3774.8 3781.4 4923.9 5244.9 5260.3 5269.5 5275.2 4966.2 5007.4 5030.6 5039.3 5049.4 5013.8 5015.2 5056.6 5064.1 5071.3 4801.8 4935.8 4952.4 4954.6 4958.6 4831.7 4953.4 5071.6 5085.8 5094.3 4885.9 4909.9 4940.9 4950.7 4955.9 15:33:01 INFO - PROCESS | 30723 | 15:33:01 INFO - PROCESS | 30723 | [#5] yui.html Cycles:180 Average:18146.15 Median:4357.91 stddev:25896.83 (594.2%) stddev-sans-first:25936.17 15:33:01 INFO - PROCESS | 30723 | Values: 654.6 661.0 662.7 664.4 731.0 11824.3 12212.5 12267.8 12304.7 12305.0 11799.8 11913.4 11918.4 11942.4 11947.2 624.5 749.0 759.6 774.4 779.7 755.5 760.6 761.2 761.6 763.2 828.8 829.1 830.4 830.5 832.1 646.4 784.9 811.0 813.1 821.0 594.9 597.5 597.6 597.9 598.6 12069.1 12078.2 12103.7 12108.5 12114.6 12401.6 12747.9 12755.2 13014.3 13040.4 11574.7 11752.6 11811.4 11872.9 12151.3 4098.3 4130.6 4158.6 4160.7 4161.0 59249.0 60602.7 60741.1 60887.4 61306.4 9560.8 9935.1 10302.5 10426.1 10482.9 19616.1 19871.2 19974.8 19995.9 20015.1 6773.6 7141.5 7165.4 7203.2 7209.2 3889.4 3985.5 4008.1 4018.0 4026.8 2824.2 2939.6 2940.1 2940.6 2942.4 496.9 497.3 503.7 504.6 509.2 455.6 455.7 512.6 555.0 558.8 8375.8 8578.8 8613.9 8759.3 8829.4 521.7 551.6 596.4 599.1 608.1 4173.2 4326.3 4334.7 4381.1 4398.8 94961.1 101925.5 102283.0 106232.0 106811.5 68905.7 71195.0 92995.5 96598.5 97155.5 70311.3 70764.0 74402.6 74810.6 75987.0 8719.3 9149.8 9225.2 9634.8 9654.8 42642.8 45328.1 45607.0 45819.5 46669.1 60057.7 66735.0 66842.3 66852.7 66899.7 11589.8 11735.1 11850.2 12208.6 12253.0 13153.9 13285.3 13576.4 13685.2 14184.6 11423.3 11805.3 12210.3 12239.9 12260.6 12362.2 13023.9 13111.0 13122.5 13146.3 11348.9 11456.8 11699.2 11794.6 12368.4 10329.5 10672.6 10736.1 10771.6 10829.7 10553.2 10910.9 10984.8 11071.3 11085.6 15:33:01 INFO - PROCESS | 30723 | -------- Summary: end -------- 15:33:01 INFO - PROCESS | 30723 | 15:33:02 INFO - TEST-INFO | 30723: exit 0 15:33:02 INFO - TEST-OK | dromaeo_css | took 1119507ms 15:33:02 INFO - TEST-START | kraken 15:33:02 INFO - operating with platform_type : linux_ 15:33:02 INFO - Initialising browser for kraken test... 15:33:02 INFO - TEST-INFO | started process 30919 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHSqnqp/profile http://localhost:53874/getInfo.html) 15:33:06 INFO - PROCESS | 30919 | __metrics Screen width/height:1600/1200 15:33:06 INFO - PROCESS | 30919 | colorDepth:24 15:33:06 INFO - PROCESS | 30919 | Browser inner width/height: 1024/697 15:33:06 INFO - PROCESS | 30919 | __metrics 15:33:07 INFO - PROCESS | 30919 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:33:07 INFO - PROCESS | 30919 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:08 INFO - TEST-INFO | 30919: exit 0 15:33:08 INFO - Browser initialized. 15:33:08 INFO - Running cycle 1/1 for kraken test... 15:33:08 INFO - TEST-INFO | started process 30998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHSqnqp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:20 INFO - PROCESS | 30998 | RSS: Main: 158826496 15:33:20 INFO - PROCESS | 30998 | 15:33:23 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53874/tests/kraken/driver.html?testName=audio-beat-detection) 15:33:24 INFO - PROCESS | 30998 | RSS: Main: 216748032 15:33:24 INFO - PROCESS | 30998 | 15:33:27 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53874/tests/kraken/driver.html?testName=audio-dft) 15:33:27 INFO - PROCESS | 30998 | RSS: Main: 204640256 15:33:27 INFO - PROCESS | 30998 | 15:33:30 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53874/tests/kraken/driver.html?testName=audio-fft) 15:33:31 INFO - PROCESS | 30998 | RSS: Main: 271175680 15:33:31 INFO - PROCESS | 30998 | 15:33:33 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53874/tests/kraken/driver.html?testName=audio-oscillator) 15:33:34 INFO - PROCESS | 30998 | RSS: Main: 181690368 15:33:34 INFO - PROCESS | 30998 | 15:33:36 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53874/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:33:37 INFO - PROCESS | 30998 | RSS: Main: 186007552 15:33:37 INFO - PROCESS | 30998 | 15:33:40 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53874/tests/kraken/driver.html?testName=imaging-darkroom) 15:33:41 INFO - PROCESS | 30998 | RSS: Main: 221691904 15:33:41 INFO - PROCESS | 30998 | 15:33:44 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53874/tests/kraken/driver.html?testName=imaging-desaturate) 15:33:45 INFO - PROCESS | 30998 | RSS: Main: 217550848 15:33:45 INFO - PROCESS | 30998 | 15:33:48 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53874/tests/kraken/driver.html?testName=json-parse-financial) 15:33:48 INFO - PROCESS | 30998 | RSS: Main: 220389376 15:33:48 INFO - PROCESS | 30998 | 15:33:50 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53874/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:33:51 INFO - PROCESS | 30998 | RSS: Main: 205787136 15:33:51 INFO - PROCESS | 30998 | 15:33:52 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:33:53 INFO - PROCESS | 30998 | RSS: Main: 211476480 15:33:53 INFO - PROCESS | 30998 | 15:33:55 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:33:56 INFO - PROCESS | 30998 | RSS: Main: 211595264 15:33:56 INFO - PROCESS | 30998 | 15:33:59 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:33:59 INFO - PROCESS | 30998 | RSS: Main: 224223232 15:33:59 INFO - PROCESS | 30998 | 15:34:02 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:34:03 INFO - PROCESS | 30998 | RSS: Main: 224915456 15:34:03 INFO - PROCESS | 30998 | 15:34:04 INFO - PROCESS | 30998 | Cycle 1(1): loaded http://localhost:53874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53874/tests/kraken/driver.html?testName=ai-astar) 15:34:05 INFO - PROCESS | 30998 | RSS: Main: 267300864 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | __start_tp_report 15:34:05 INFO - PROCESS | 30998 | _x_x_mozilla_page_load 15:34:05 INFO - PROCESS | 30998 | _x_x_mozilla_page_load_details 15:34:05 INFO - PROCESS | 30998 | |i|pagename|runs| 15:34:05 INFO - PROCESS | 30998 | |0;ai-astar;107;116;116;117;115;113;115;116;113;114 15:34:05 INFO - PROCESS | 30998 | |1;audio-beat-detection;149;122;123;172;129;129;146;122;157;128 15:34:05 INFO - PROCESS | 30998 | |2;audio-dft;162;161;162;161;161;164;163;162;161;163 15:34:05 INFO - PROCESS | 30998 | |3;audio-fft;74;76;76;122;76;76;76;75;106;74 15:34:05 INFO - PROCESS | 30998 | |4;audio-oscillator;85;86;120;84;85;85;124;85;86;125 15:34:05 INFO - PROCESS | 30998 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 15:34:05 INFO - PROCESS | 30998 | |6;imaging-darkroom;147;146;146;146;146;146;146;146;146;147 15:34:05 INFO - PROCESS | 30998 | |7;imaging-desaturate;95;95;95;96;95;95;95;96;96;95 15:34:05 INFO - PROCESS | 30998 | |8;json-parse-financial;63;63;63;63;64;64;62;64;63;64 15:34:05 INFO - PROCESS | 30998 | |9;json-stringify-tinderbox;64;64;65;65;65;74;64;64;66;66 15:34:05 INFO - PROCESS | 30998 | |10;stanford-crypto-aes;79;78;78;78;79;80;82;82;89;79 15:34:05 INFO - PROCESS | 30998 | |11;stanford-crypto-ccm;128;129;126;133;131;129;129;137;206;140 15:34:05 INFO - PROCESS | 30998 | |12;stanford-crypto-pbkdf2;171;165;164;168;168;164;167;169;164;166 15:34:05 INFO - PROCESS | 30998 | |13;stanford-crypto-sha256-iterative;70;67;70;66;66;66;67;66;67;65 15:34:05 INFO - PROCESS | 30998 | __end_tp_report 15:34:05 INFO - PROCESS | 30998 | __start_cc_report 15:34:05 INFO - PROCESS | 30998 | _x_x_mozilla_cycle_collect,1936 15:34:05 INFO - PROCESS | 30998 | __end_cc_report 15:34:05 INFO - PROCESS | 30998 | __startTimestamp1468276445223__endTimestamp 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | ------- Summary: start ------- 15:34:05 INFO - PROCESS | 30998 | Number of tests: 14 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.50 stddev:2.86 (2.5%) stddev-sans-first:1.41 15:34:05 INFO - PROCESS | 30998 | Values: 107.0 116.0 116.0 117.0 115.0 113.0 115.0 116.0 113.0 114.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:137.50 stddev:17.32 (12.6%) stddev-sans-first:17.88 15:34:05 INFO - PROCESS | 30998 | Values: 149.0 122.0 123.0 172.0 129.0 129.0 146.0 122.0 157.0 128.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 15:34:05 INFO - PROCESS | 30998 | Values: 162.0 161.0 162.0 161.0 161.0 164.0 163.0 162.0 161.0 163.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.00 stddev:16.74 (22.0%) stddev-sans-first:17.42 15:34:05 INFO - PROCESS | 30998 | Values: 74.0 76.0 76.0 122.0 76.0 76.0 76.0 75.0 106.0 74.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:85.00 stddev:18.34 (21.6%) stddev-sans-first:18.97 15:34:05 INFO - PROCESS | 30998 | Values: 85.0 86.0 120.0 84.0 85.0 85.0 124.0 85.0 86.0 125.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:34:05 INFO - PROCESS | 30998 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 15:34:05 INFO - PROCESS | 30998 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:34:05 INFO - PROCESS | 30998 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.50 stddev:0.67 (1.1%) stddev-sans-first:0.71 15:34:05 INFO - PROCESS | 30998 | Values: 63.0 63.0 63.0 63.0 64.0 64.0 62.0 64.0 63.0 64.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.00 stddev:3.02 (4.6%) stddev-sans-first:3.14 15:34:05 INFO - PROCESS | 30998 | Values: 64.0 64.0 65.0 65.0 65.0 74.0 64.0 64.0 66.0 66.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.50 stddev:3.37 (4.2%) stddev-sans-first:3.54 15:34:05 INFO - PROCESS | 30998 | Values: 79.0 78.0 78.0 78.0 79.0 80.0 82.0 82.0 89.0 79.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:132.00 stddev:24.00 (18.2%) stddev-sans-first:25.13 15:34:05 INFO - PROCESS | 30998 | Values: 128.0 129.0 126.0 133.0 131.0 129.0 129.0 137.0 206.0 140.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.50 stddev:2.41 (1.4%) stddev-sans-first:1.96 15:34:05 INFO - PROCESS | 30998 | Values: 171.0 165.0 164.0 168.0 168.0 164.0 167.0 169.0 164.0 166.0 15:34:05 INFO - PROCESS | 30998 | 15:34:05 INFO - PROCESS | 30998 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.70 (2.5%) stddev-sans-first:1.41 15:34:05 INFO - PROCESS | 30998 | Values: 70.0 67.0 70.0 66.0 66.0 66.0 67.0 66.0 67.0 65.0 15:34:05 INFO - PROCESS | 30998 | -------- Summary: end -------- 15:34:05 INFO - PROCESS | 30998 | 15:34:06 INFO - TEST-INFO | 30998: exit 0 15:34:06 INFO - TEST-OK | kraken | took 63862ms 15:34:06 INFO - SUITE-END | took 1183s 15:34:06 INFO - Completed test suite (00:19:43) 15:34:06 INFO - javascript benchmark 15:34:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2585.392432778297, 2631.552636051871, 2654.283343497256, 2658.0696756135353, 2676.772474339681, 4557.817687292512, 4662.0, 4718.032803275329, 4723.362346083282, 4770.570648641621, 4626.930596041054, 4638.373819534362, 4669.6731228814, 4722.669413141081, 4735.171345014626, 4082.203970225807, 4219.0, 4299.828006879721, 4328.63206627437, 4469.709468884512, 4374.671899607526, 4404.647628189737, 4425.31407631816, 4432.556744325558, 4459.331100334959, 4204.369344598319, 4263.424437700902, 4320.1575692739925, 4352.934705979413, 4397.758123303217, 4292.1630282095, 4294.935575966363, 4304.031592891605, 4411.09572537629, 4450.065486247891, 4321.546237645033, 4346.043870348518, 4355.1725172217175, 4399.186150562125, 4401.075774087445, 4612.976935115303, 4673.275642275453, 4723.36234608327, 4738.47876733559, 4776.259679749644, 5115.0, 5121.078205922932, 5136.203888397305, 5138.254953031789, 5139.254808052811, 5126.948730512684, 5135.178371460548, 5150.356205474316, 5156.994386094713, 5162.199859021857, 4557.043020965568, 4659.5806377425515, 4701.64737644678, 4714.292856071548, 4732.455767586737, 5152.175651895754, 5167.354080739908, 5167.612429067835, 5225.843224703265, 5235.790568377299, 4954.628402869727, 4954.851354459372, 4987.10232158215, 5003.749812509433, 5011.173156429221, 4876.195427754381, 4999.775010124553, 5062.139436295838, 5072.746362681851, 5084.084864724311, 4996.07572599063, 5031.471695471922, 5035.370578677665, 5035.672681275779, 5082.2122571001555, 4774.665773395829, 4998.90002199954, 5008.048470790538, 5008.974955125201, 5011.09800235961, 4185.351270553069, 4211.7472951623, 4212.68404869636, 4256.808443620044, 4306.461692288464, 3706.9073273168387, 3736.140687641803, 3738.36447803874, 3773.01901505605, 3774.5281839770028, 4273.145370925828, 4282.229198744194, 4283.22901877665, 4325.2214601371425, 4333.111712099012, 4900.019996000814, 4922.778474968635, 4990.101781679333, 5039.722815245197, 5058.620603454682, 4294.3558466230315, 4309.202797482413, 4319.416878721395, 4320.805563749576, 4362.585554372329, 4534.88662783433, 4535.183666939983, 4537.1833070047705, 4538.205813982539, 4589.632829373642, 4601.240795268744, 4697.295405689174, 4749.548792864673, 4758.547937945889, 4765.523447655207, 5064.924026139537, 5068.29043933857, 5082.923756143735, 5083.491650835035, 5138.845834624967, 4520.660950428797, 4719.244920812654, 4751.524847515221, 4760.738159401266, 4808.2787581863, 4932.679375840702, 4954.900901982012, 4984.352034235526, 5001.924971125363, 5004.674696144739, 4985.52637499437, 5012.223105418666, 5020.297158397754, 5021.49785021495, 5068.442471328077, 4733.786979585858, 4895.510448955219, 4900.8774780630765, 4912.091263116335, 4953.257011448168, 4928.728919909439, 4954.1825598775795, 4979.800807967786, 4982.377202849644, 5024.4473107957365, 4843.128236917248, 4852.2479015752615, 4858.368412106403, 4908.92636610444, 4914.827981020647, 4810.63920205979, 4840.951590484191, 4844.0, 4996.725180115129, 5035.0685123252315, 4646.883827904329, 4688.179568575554, 4691.483936767021, 4723.527647235249, 4743.715377077248], "value": 4621.5396045634125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.9168305095906, 584.4389386189258, 590.0087852407954, 591.2254946020711, 601.0503404620698, 27176.59235111475, 27703.584446233297, 29797.106086817403, 40811.79594102025, 42014.579854201445, 41361.17277654445, 42628.36057459133, 42720.0, 42727.572724272824, 46657.300140497835, 7746.845063098749, 7869.763907082796, 7892.763217103496, 7900.644470998819, 7901.841963160733, 7467.327940485356, 7780.0, 10200.0, 10490.895091049106, 10494.422806745626, 10094.041066098709, 10503.159747220205, 10510.632127875526, 10520.0, 10522.105621022223, 10381.948090259539, 10428.89571104287, 10463.424511651856, 10468.581256749721, 10469.947650261738, 10279.383237005728, 10359.171266298677, 10367.688969330931, 10369.170466362673, 10374.948125259401, 40593.0, 40874.79562602168, 40907.795461022804, 41013.384799228035, 41078.79460602708, 3089.5365695145683, 3255.7232635226032, 3265.673432656739, 3607.8556857725657, 3647.708183345326, 31137.0, 31421.0, 31806.204844879063, 31847.681523184703, 31877.0, 3140.764442666786, 3180.236743181643, 3193.1218914798615, 3196.089678046839, 3223.7098661120613, 276892.0, 277164.0, 278383.0, 278991.0, 279164.0, 59117.704411477665, 59923.101153482734, 59935.99999999913, 59949.70025149934, 60051.699741501885, 54391.18413223805, 63251.68374158192, 63457.682711586145, 63516.0, 63520.364796351445, 13016.609501714964, 13391.665208369868, 13412.591677873877, 13417.865821341662, 13420.328983550979, 8867.733967980841, 10303.536340864679, 10373.948130259301, 10425.165986721044, 10431.634892778717, 18291.0, 18917.432477025435, 18999.905000475184, 19025.904870475835, 19086.236550538102, 7141.607211603412, 7230.313120253568, 7248.238934911758, 7332.303431174137, 7338.633068346561, 794.6225542867207, 875.9255463285565, 927.9089897366143, 940.0270719804969, 1008.7578981044496, 28220.576691349645, 28551.71448285532, 28556.714432855406, 29036.7096329034, 29101.272468188465, 891.3136884598953, 958.2573505533128, 963.8554216867526, 988.6342053440274, 1002.368507840056, 14193.361298741582, 14231.857681423053, 14317.284135793168, 14421.855781442051, 14622.926885365718, 368439.0, 369228.0, 374253.0, 376524.0, 376945.0, 50483.49516504861, 50497.0, 50792.49207507951, 50901.49098508967, 50926.49073509217, 1088.276296262992, 1158.901493373056, 1182.9645110646695, 1196.0371900620084, 1214.2957084890918, 28355.0, 29322.706772932852, 29377.0, 29666.703332966394, 30213.848930756085, 41152.794236028625, 41839.7908010458, 41890.37164442597, 41928.3710744333, 41941.58058419377, 237412.0, 240977.0, 241278.0, 241336.0, 242918.0, 32209.51685724669, 36686.798792072565, 37477.43783843299, 38236.80881595686, 42092.78953605212, 41754.79122604368, 41813.0, 42061.78969105135, 42338.57661423346, 42566.0, 36036.63963360333, 36357.27285454329, 36553.63446365502, 36655.26689466142, 37022.8148859254, 35402.46896296506, 36563.63436365602, 37110.62889371072, 37187.81406092952, 37256.0, 32364.83817580897, 32644.34711305808, 32650.34699306048, 32831.50752738663, 32899.0, 30843.0, 34045.829770850985, 36178.8191059043, 36213.63786362208, 36522.0, 25513.489730205663, 25965.740342597088, 26110.86944565265, 26148.8692556536, 26569.0], "value": 18275.993375862166, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.47497112819065, 367.13173345038973, 371.6655010490559, 373.3764613096129, 386.67712460095834, 13106.868931310684, 15128.016678915863, 17320.307187712475, 17379.391721289758, 17420.738688919675, 16954.40659576915, 17763.289468421248, 17981.550461238476, 18032.27870885163, 18046.0, 5265.921011184826, 5439.5920305977015, 5477.616566840323, 5479.342478902538, 5481.492589537889, 3738.495303134083, 5349.5720342372715, 6013.218281623383, 6295.685215739218, 6350.777722779703, 6165.660888651122, 6405.967970160166, 6444.548881578291, 6444.581102228341, 6453.741850325981, 6298.0552917062805, 6314.842128946813, 6317.873642527146, 6326.999999999977, 6364.49084073273, 3432.2792213635166, 5220.530152286275, 5516.862078448031, 5522.282103326542, 5568.49883510482, 21360.145594176214, 21740.456488587755, 21986.340409787732, 22041.559168816533, 22042.559148816934, 1928.2479832865195, 1942.6988816733428, 1976.8912709800954, 1993.5215548268457, 2002.7196192533056, 13372.665683357896, 13630.591082267549, 13764.724705505934, 13856.445742170301, 14103.929480352635, 1603.4708546179734, 1669.7745804316505, 1731.6709825133184, 1737.6264103217743, 1766.97349539757, 45457.0, 64026.0, 65441.0, 70920.0, 72838.2716172842, 9904.55429505674, 10128.746781330527, 10132.645357412455, 10331.468790146311, 10336.431496267778, 13020.934895325463, 13070.477180912658, 13117.60647180586, 13277.601671949857, 13536.0, 7102.573845569283, 7290.015847860577, 7311.378532824663, 7357.742479013209, 7388.926110738931, 6917.273686262971, 6944.930550694529, 7050.365467107985, 7062.92937070633, 7074.115735533059, 6255.343188965184, 6321.55749097568, 6348.873022539523, 6361.409647588103, 6464.838379040561, 1716.3048965169125, 1752.8685348598651, 1777.4667599720292, 1798.4694515118072, 1813.7279408088627, 786.3591173193829, 820.6717313074698, 827.3050637464559, 848.4315508609124, 849.180540778152, 17372.0, 18317.17572709231, 18330.63338733218, 18469.445916622524, 18628.72056919147, 842.2546046748549, 869.4174902815129, 877.4688169144756, 894.7584152278848, 930.5765876526165, 9839.114479696862, 10082.647107351355, 10288.228382871315, 10470.895291046992, 10549.789004220027, 384696.0, 387737.0, 388231.0, 388367.0, 395724.0, 50484.747576261885, 52409.2138617921, 62278.0, 88321.0, 90283.54858225667, 909.3316412436853, 910.2354022620898, 934.0472717827913, 946.5929650250458, 949.8100379924043, 12389.75220495603, 15784.973976691479, 15954.521364359089, 16135.112568808829, 17142.057186854843, 26981.0, 28074.578881317204, 36382.726604568714, 38594.421083683206, 39239.60760392359, 47796.0, 48594.51405486041, 52657.21014184867, 52952.0, 53294.200586992, 6536.0, 6777.491688123312, 6825.215100263533, 7005.894911576433, 7747.92252077472, 6053.3946605339115, 8243.917560824555, 8297.502149871027, 9346.01866804003, 11345.205835591429, 9627.614895404105, 9748.366356187109, 9936.056074672895, 10002.599376149818, 10122.848157277795, 4505.7296562206375, 5448.509634132947, 9780.41317520951, 10274.383536987805, 10357.4266877927, 10017.348872323275, 10039.498025098861, 10109.848352274576, 10205.591776328863, 10416.166706663296, 6979.40675042627, 7029.894551581628, 7030.3321184487395, 7166.104236970379, 7222.241664625139, 9891.554880030271, 9994.950025249827, 10070.597176112873, 10183.287169898036, 10276.588936442758], "value": 8258.735555305748, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.885939695126, 1381.0608786025502, 1398.5454727213653, 1617.555172327609, 1642.1050527462533, 7132.821679458009, 7545.7358992435275, 7590.620468976543, 7609.086909570852, 7619.809504762384, 6412.230532336126, 7053.15362156542, 7265.455090868193, 7303.598302093383, 7424.146223184335, 2131.2434085899476, 2153.9569208615817, 2160.5354848707525, 2163.145557504785, 2164.512984578473, 2134.4770531219874, 2144.914203431869, 2183.6942828004094, 2185.5410363823617, 2187.037703410502, 1829.0488945748202, 2092.9058192381303, 2150.0862133593237, 2152.6448136057534, 2180.934571962836, 2150.40863762465, 2151.9354419367446, 2170.9240176593744, 2173.7717539658347, 2205.6801763744165, 2146.9785302146934, 2150.1399440224036, 2151.2900742754855, 2153.23560136151, 2157.6224160771803, 7563.508371955805, 7622.237776222389, 7627.2372762723835, 7629.961850190769, 7634.6182690865235, 4292.785360731951, 4451.577100175478, 4454.265046267362, 4455.22033644111, 4457.0, 2670.6661667291587, 2776.1393967870026, 2793.0782841662203, 2793.483205606969, 2797.0, 839.4627438439404, 867.7136544940155, 872.3021582733787, 880.5773228850188, 884.6417201033591, 9995.600175992951, 10013.098821106063, 10031.498425078717, 10054.044865737817, 10066.094051535398, 3948.5853985331128, 4026.798660066985, 4154.979225103855, 4161.271777438936, 4167.74993500391, 8429.241368276884, 8487.278581320534, 8489.830203395897, 8491.61787719554, 8499.022612399529, 4464.508904020555, 4468.396766436555, 4470.664700147502, 4489.304157855538, 4493.123840851068, 3985.9202815943518, 4033.4554835097474, 4040.8383664653675, 4042.312806822847, 4042.3532234842282, 3503.649635036527, 3558.1104723819044, 3619.131408462003, 3626.6192049834917, 3632.146445585285, 1005.6932635546228, 1090.4602221900052, 1146.4439746722833, 1151.988480115205, 1155.589765633205, 646.683125268615, 744.4639859301294, 814.8248126652799, 834.0, 842.2588122452325, 4721.433427988663, 4801.903961920742, 4855.757212139379, 4871.0, 4891.413030436299, 791.0546896458792, 840.6091167607092, 849.5539841583095, 852.497026754218, 857.8841856349438, 3343.297907439416, 3351.815650139266, 3386.72906167506, 3421.7775844570024, 3440.0539851540525, 8642.22220000203, 9055.637774489078, 9147.817043659223, 9167.12912273333, 9170.358074934691, 7887.723929662549, 9016.774580635536, 9037.186653201243, 9108.726738197865, 9113.589888455035, 6476.773312934119, 6771.424428923498, 6773.8983915241315, 6923.826904327331, 6941.236463988957, 4842.927356089591, 4942.825156930939, 4943.283223932582, 4975.5522003019905, 4997.300377947017, 8271.95864020676, 8388.245057944814, 8496.447730897471, 8499.617517211616, 8500.36247281444, 8060.919390806017, 8403.537805420761, 8566.957165214135, 8651.264642505206, 8652.740417787476, 6460.741570337133, 6586.80239592813, 6596.208454985433, 6602.801915942529, 6612.603243805387, 6915.792526224222, 6971.058907047483, 7013.263607321362, 7111.288871112847, 7125.501214915113, 4736.8578942631775, 6174.598651087665, 6198.008318669172, 6266.937330626635, 6378.202724659418, 6348.873022539616, 6440.516961227833, 6464.191976003, 6486.2216534016225, 6575.80272591823, 5643.887122257614, 5655.179998900218, 5727.0, 5772.711364431678, 5833.4749872511675, 4174.853880114181, 4240.681948853787, 4329.826806927687, 4359.978200108979, 4407.052483715888, 4410.536893626123, 4462.598366147063, 4481.887952801206, 4549.545045495424, 4552.7723613819835], "value": 3945.8178048265695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.9357741296915, 297.23462085130785, 298.5223642172523, 305.92504836315106, 306.79291478252173, 4840.467548569654, 4888.633352498568, 4910.508949105088, 4926.137925862978, 4932.358793356863, 4802.08760335536, 4814.855554333367, 4853.805847766094, 4860.90629608338, 4868.805247790093, 3651.434027725707, 3727.5154229950067, 3777.3200823851694, 3795.2978698940783, 3811.1615444602144, 3751.23099764549, 3783.3920583751933, 3786.318462676717, 3787.602301758317, 3821.7706937583835, 3778.716596255278, 3780.527434070741, 3783.8864834054884, 3788.7726736395903, 3809.961900381002, 3578.7137029037613, 3681.4109742441346, 3767.830447629863, 3775.6413140751583, 3791.9431208531896, 3339.5491608632765, 3343.1307859956587, 3348.547946027304, 3352.547406100194, 3357.9496307555405, 4730.195866702635, 4732.195526760424, 4738.005018946025, 4753.643476739224, 4758.78585463655, 4218.367244913257, 4290.0, 4338.58783415575, 4344.0, 4360.280553708634, 4927.61873373978, 4964.106460837048, 5021.723805190713, 5022.347094877679, 5042.369703787027, 3622.1125824173246, 3632.5277713897026, 3661.176235347052, 3678.742488025839, 3692.2615476904457, 5146.897062058775, 5257.211418287249, 5280.577553795687, 5283.788648454057, 5284.894302113936, 3657.213699054716, 3659.4144936809985, 3659.652333028358, 3677.3748462761396, 3686.562240726119, 3726.8136593170775, 3730.7948062856262, 3739.0, 3739.3456145174487, 3741.3265612190066, 4083.9183216335505, 4273.508546517191, 4275.828966841292, 4276.828926842892, 4287.421198138274, 5008.5993120550265, 5018.924716129261, 5023.2214006829, 5042.823501177441, 5043.420006699276, 1261.993690031544, 1279.2004996876951, 1286.1125823181974, 1287.3241548187088, 1288.5103660608909, 809.8987626546682, 837.6146972392768, 854.7734850264685, 855.3883972959239, 863.0721973878107, 4316.179925814085, 4330.631896288851, 4350.891227719269, 4353.455818022747, 4355.5208927018, 5063.3417655704525, 5076.6192535559985, 5081.796728130834, 5084.6440749147205, 5099.745012749422, 4303.698741088157, 4317.244482215599, 4326.015049615755, 4339.717918335298, 4342.088161486124, 2615.5030544196543, 2756.490049340878, 2758.903438379647, 2760.0, 2760.323720688444, 4840.709557426566, 4853.830115945995, 4861.596882182486, 4862.440819305754, 4879.4388645305535, 5080.872978175576, 5294.841154765363, 5319.308489896289, 5334.51989320963, 5343.198520221998, 4844.297576851407, 4844.515548445198, 4850.563449289581, 4896.4124305083615, 4906.0, 5145.9228111579105, 5184.740762961912, 5189.76646050921, 5193.298904647975, 5199.896002080013, 4937.5927860561005, 5121.79512819498, 5122.769475373542, 5130.384353877558, 5132.1267737274875, 4876.195427754381, 4880.779805048738, 4888.633352498506, 4888.877778055577, 4899.1426500363, 3720.2187540616774, 3730.384486559687, 3767.9434808478445, 3774.75464094833, 3781.3760729479327, 4923.901521969469, 5244.947550524446, 5260.263563101245, 5269.525742683177, 5275.155975043975, 4966.155753521838, 5007.449180590059, 5030.62270329734, 5039.319691841701, 5049.419316778471, 5013.846815232444, 5015.22264049073, 5056.595472362275, 5064.139096353555, 5071.290019397214, 4801.807927682854, 4935.802567897387, 4952.4304704959395, 4954.5540901319055, 4958.603311734981, 4831.661783675249, 4953.405591329064, 5071.594272458121, 5085.753990272407, 5094.2613321068975, 4885.877853053702, 4909.9018019640125, 4940.92588611164, 4950.727709975842, 4955.900881982412], "value": 3799.911361543066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.6039646014161, 660.9537332386733, 662.6653539962318, 664.4252721395991, 730.9729829589429, 11824.290542567453, 12212.511499540007, 12267.815982760243, 12304.692382690437, 12305.0, 11799.823002654966, 11913.404329783498, 11918.404079796019, 11942.402879855994, 11947.223430477014, 624.5472032776246, 749.048708140661, 759.6163937211701, 774.4075782026754, 779.7193010516238, 755.5315704263364, 760.5664771080486, 761.1817296406385, 761.603965937712, 763.1795819494039, 828.8093738440162, 829.1004260377467, 830.4270053662983, 830.4560512864053, 832.1262674192075, 646.4279112985018, 784.8783438566974, 811.0146172400562, 813.0649752784286, 820.9671613135467, 594.8839976204641, 597.4742226840352, 597.5578072226565, 597.8953683105439, 598.5600583571072, 12069.094817888606, 12078.214916030518, 12103.697407564794, 12108.455119519644, 12114.575989840314, 12401.62795116148, 12747.936260318638, 12755.234685918875, 13014.284214368206, 13040.413181406764, 11574.6527604172, 11752.647420577396, 11811.350375729331, 11872.88127118735, 12151.270923744603, 4098.262312783698, 4130.648894843942, 4158.563350848148, 4160.667146628292, 4160.979195104035, 59249.0, 60602.69698651523, 60741.088883666336, 60887.391126088616, 61306.38693613051, 9560.760980975392, 9935.105840474247, 10302.536385862653, 10426.061654451136, 10482.947585262025, 19616.11727472267, 19871.205151794056, 19974.800251997585, 19995.900020499805, 20015.09932053061, 6773.593584384953, 7141.500094993405, 7165.426765858719, 7203.207647158808, 7209.243029481934, 3889.3971434427144, 3985.4818873546833, 4008.0781420272915, 4017.959820401817, 4026.798660066985, 2824.237455886899, 2939.617849679528, 2940.088572542476, 2940.6324209473814, 2942.411517696469, 496.9478204788445, 497.2640492071754, 503.66506273327826, 504.634140248317, 509.18784538660776, 455.562659846551, 455.71062375391864, 512.5720023780185, 554.9500544950973, 558.7539786276626, 8375.790605234795, 8578.828423431622, 8613.870791938127, 8759.255463285692, 8829.402128178792, 521.7417378397718, 551.6166264446172, 596.4304089594427, 599.12228585123, 608.0878681977034, 4173.227952828736, 4326.264535029052, 4334.696571239983, 4381.123775244963, 4398.846040388571, 94961.05038949661, 101925.49037254766, 102283.0, 106232.0, 106811.46594267135, 68905.65547172232, 71195.0, 92995.53502232446, 96598.51700741593, 97155.51422242985, 70311.2968870315, 70764.0, 74402.62798685972, 74810.625946871, 75986.9999999989, 8719.346049046473, 9149.77125571866, 9225.21585665226, 9634.807303853744, 9654.75863103428, 42642.78678606711, 45328.09343813171, 45607.0, 45819.541804581524, 46669.06661866812, 60057.69971150116, 66735.0, 66842.33157668555, 66852.665736671, 66899.66550167218, 11589.768204635691, 11735.119866009913, 11850.170488065753, 12208.633740987785, 12253.0, 13153.868461315647, 13285.269310188032, 13576.389062492013, 13685.247311397969, 14184.574462766133, 11423.257488263236, 11805.291682498733, 12210.328431936328, 12239.877601223874, 12260.632181034583, 12362.196457230251, 13023.934880325538, 13111.0, 13122.540711075448, 13146.34268286601, 11348.943255283672, 11456.770864582828, 11699.181057326246, 11794.646160615195, 12368.44342004594, 10329.522528580976, 10672.62645807393, 10736.141108711128, 10771.569137234423, 10829.729256768644, 10553.18810779677, 10910.890891090987, 10984.780304394028, 11071.335719856834, 11085.612003579836], "value": 6697.318868979682, "unit": "score"}], "name": "dromaeo_css", "value": 6420.5819534786615, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 116.0, 117.0, 115.0, 113.0, 115.0, 116.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 123.0, 172.0, 129.0, 129.0, 146.0, 122.0, 157.0, 128.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 161.0, 164.0, 163.0, 162.0, 161.0, 163.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 122.0, 76.0, 76.0, 76.0, 75.0, 106.0, 74.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 120.0, 84.0, 85.0, 85.0, 124.0, 85.0, 86.0, 125.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 62.0, 64.0, 63.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 65.0, 74.0, 64.0, 64.0, 66.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 79.0, 80.0, 82.0, 82.0, 89.0, 79.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 126.0, 133.0, 131.0, 129.0, 129.0, 137.0, 206.0, 140.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 165.0, 164.0, 168.0, 168.0, 164.0, 167.0, 169.0, 164.0, 166.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1533.6999999999998, "alertThreshold": 2.0}]} 15:34:06 INFO - Return code: 0 15:34:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:34:06 INFO - # TBPL SUCCESS # 15:34:06 INFO - Running post-action listener: _resource_record_post_action 15:34:06 INFO - ##### 15:34:06 INFO - ##### Finished run-tests step (success) 15:34:06 INFO - ##### 15:34:06 INFO - Running post-run listener: _resource_record_post_run 15:34:06 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547418112; Read time: 12; Write time: 482104 15:34:06 INFO - TinderboxPrint: CPU usage
13.0% 15:34:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:34:06 INFO - TinderboxPrint: I/O write bytes / time
547,418,112 / 482,104 15:34:06 INFO - TinderboxPrint: CPU idle
8,333.9 (87.0%) 15:34:06 INFO - TinderboxPrint: CPU user
1,201.6 (12.5%) 15:34:06 INFO - TinderboxPrint: Swap in / out
0 / 0 15:34:06 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 259239936; Read time: 0; Write time: 293308 15:34:06 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288178176; Read time: 0; Write time: 188796 15:34:07 INFO - Running post-run listener: _upload_blobber_files 15:34:07 INFO - Blob upload gear active. 15:34:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:34:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:34:07 INFO - (blobuploader) - INFO - Open directory for files ... 15:34:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:34:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:34:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:08 INFO - (blobuploader) - INFO - Done attempting. 15:34:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:34:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:34:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:11 INFO - (blobuploader) - INFO - Done attempting. 15:34:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:34:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:34:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:12 INFO - (blobuploader) - INFO - Done attempting. 15:34:12 INFO - (blobuploader) - INFO - Iteration through files over. 15:34:12 INFO - Return code: 0 15:34:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:34:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:34:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/79b334a80f9087a15e649ad2314d5e83edeb38733f462340215320ebeb7bc420e94080e145fc94939f24059701cdbd5016b508aa49cfe61655204dafcd3024b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11af0ac36ea8beb2c11d636399a283f70f495c54137529ec521423e6a5f7a02958f00f0045656bb9cfa3df13bbaf278916d314da112e56b2144166b181cf896", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b86af97396e726ba13892a8e781f4e2910fb3d348839c62648b872263cfbdea6b9be6261444d871d720497949387a9820782ec7c5ad680b76b6fa1f51f611e8"} 15:34:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:34:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:34:12 INFO - Contents: 15:34:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/79b334a80f9087a15e649ad2314d5e83edeb38733f462340215320ebeb7bc420e94080e145fc94939f24059701cdbd5016b508aa49cfe61655204dafcd3024b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11af0ac36ea8beb2c11d636399a283f70f495c54137529ec521423e6a5f7a02958f00f0045656bb9cfa3df13bbaf278916d314da112e56b2144166b181cf896", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b86af97396e726ba13892a8e781f4e2910fb3d348839c62648b872263cfbdea6b9be6261444d871d720497949387a9820782ec7c5ad680b76b6fa1f51f611e8"} 15:34:12 INFO - Running post-run listener: copy_logs_to_upload_dir 15:34:12 INFO - Copying logs to upload dir... 15:34:12 INFO - mkdir: /builds/slave/test/build/upload/logs 15:34:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.613231 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-11 15:34:12.388975) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 15:34:12.394500) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/79b334a80f9087a15e649ad2314d5e83edeb38733f462340215320ebeb7bc420e94080e145fc94939f24059701cdbd5016b508aa49cfe61655204dafcd3024b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11af0ac36ea8beb2c11d636399a283f70f495c54137529ec521423e6a5f7a02958f00f0045656bb9cfa3df13bbaf278916d314da112e56b2144166b181cf896", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b86af97396e726ba13892a8e781f4e2910fb3d348839c62648b872263cfbdea6b9be6261444d871d720497949387a9820782ec7c5ad680b76b6fa1f51f611e8"} build_url:https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/Y6KdBvKPSbiq4DEFmOlN1w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/79b334a80f9087a15e649ad2314d5e83edeb38733f462340215320ebeb7bc420e94080e145fc94939f24059701cdbd5016b508aa49cfe61655204dafcd3024b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11af0ac36ea8beb2c11d636399a283f70f495c54137529ec521423e6a5f7a02958f00f0045656bb9cfa3df13bbaf278916d314da112e56b2144166b181cf896", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b86af97396e726ba13892a8e781f4e2910fb3d348839c62648b872263cfbdea6b9be6261444d871d720497949387a9820782ec7c5ad680b76b6fa1f51f611e8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 15:34:12.419928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 15:34:12.420292) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468273935.913345-1018230592 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004383 ========= master_lag: 1.76 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 15:34:14.180231) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 15:34:14.180586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 15:34:14.181377) ========= ========= Total master_lag: 1.84 =========