builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1467241456.8 results: success (0) buildid: 20160629144828 builduid: 50e4a1a8a2c1465fa3e9bcb56a802ffd revision: e2525c03c65f1a0f81cd344e1aca57f22608ae42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:16.795595) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:16.796068) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:16.796359) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:16.812410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:16.812698) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-29 16:04:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.0M=0s 2016-06-29 16:04:17 (41.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.245382 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.072244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.072541) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015724 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.122252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.122631) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e2525c03c65f1a0f81cd344e1aca57f22608ae42 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e2525c03c65f1a0f81cd344e1aca57f22608ae42 --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=78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-29 16:04:17,378 truncating revision to first 12 chars 2016-06-29 16:04:17,378 Setting DEBUG logging. 2016-06-29 16:04:17,378 attempt 1/10 2016-06-29 16:04:17,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e2525c03c65f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 16:04:17,818 unpacking tar archive at: autoland-e2525c03c65f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.770293 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.906883) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.907225) ========= script_repo_revision: e2525c03c65f1a0f81cd344e1aca57f22608ae42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.907749) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.908314) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 16:04:17.918240) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-06-29 16:04:17.918638) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:04:18 INFO - MultiFileLogger online at 20160629 16:04:18 in /builds/slave/test 16:04:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 16:04:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:04:18 INFO - {'append_to_log': False, 16:04:18 INFO - 'base_work_dir': '/builds/slave/test', 16:04:18 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 16:04:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:04:18 INFO - 'branch': 'Autoland-Non-PGO', 16:04:18 INFO - 'buildbot_json_path': 'buildprops.json', 16:04:18 INFO - 'config_files': ('talos/linux_config.py',), 16:04:18 INFO - 'default_actions': ('clobber', 16:04:18 INFO - 'read-buildbot-config', 16:04:18 INFO - 'download-and-extract', 16:04:18 INFO - 'populate-webroot', 16:04:18 INFO - 'create-virtualenv', 16:04:18 INFO - 'install', 16:04:18 INFO - 'run-tests'), 16:04:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:04:18 INFO - 'download_minidump_stackwalk': True, 16:04:18 INFO - 'download_symbols': 'ondemand', 16:04:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:04:18 INFO - 'tooltool.py': '/tools/tooltool.py', 16:04:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:04:18 INFO - '/tools/misc-python/virtualenv.py')}, 16:04:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:04:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:04:18 INFO - 'installer_path': 'installer.exe', 16:04:18 INFO - 'log_level': 'info', 16:04:18 INFO - 'log_name': 'talos', 16:04:18 INFO - 'log_to_console': True, 16:04:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:04:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:04:18 INFO - 'opt_config_files': (), 16:04:18 INFO - 'pip_index': False, 16:04:18 INFO - 'sps_profile': False, 16:04:18 INFO - 'sps_profile_interval': 0, 16:04:18 INFO - 'suite': 'dromaeojs', 16:04:18 INFO - 'system_bits': '32', 16:04:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:04:18 INFO - 'title': 'talos-linux64-ix-019', 16:04:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:04:18 INFO - 'use_talos_json': True, 16:04:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:04:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:04:18 INFO - 'work_dir': 'build'} 16:04:18 INFO - ##### 16:04:18 INFO - ##### Running clobber step. 16:04:18 INFO - ##### 16:04:18 INFO - Running pre-action listener: _resource_record_pre_action 16:04:18 INFO - Running main action method: clobber 16:04:18 INFO - rmtree: /builds/slave/test/build 16:04:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:04:18 INFO - Running post-action listener: _resource_record_post_action 16:04:18 INFO - ##### 16:04:18 INFO - ##### Finished clobber step (success) 16:04:18 INFO - ##### 16:04:18 INFO - ##### 16:04:18 INFO - ##### Running read-buildbot-config step. 16:04:18 INFO - ##### 16:04:18 INFO - Running pre-action listener: _resource_record_pre_action 16:04:18 INFO - Running main action method: read_buildbot_config 16:04:18 INFO - Using buildbot properties: 16:04:18 INFO - { 16:04:18 INFO - "project": "", 16:04:18 INFO - "product": "firefox", 16:04:18 INFO - "script_repo_revision": "production", 16:04:18 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 16:04:18 INFO - "repository": "", 16:04:18 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 16:04:18 INFO - "buildid": "20160629144828", 16:04:18 INFO - "pgo_build": "False", 16:04:18 INFO - "basedir": "/builds/slave/test", 16:04:18 INFO - "buildnumber": 5, 16:04:18 INFO - "slavename": "talos-linux64-ix-019", 16:04:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:04:18 INFO - "platform": "ubuntu64_hw", 16:04:18 INFO - "scheduler": "tests-autoland-linux64-talos", 16:04:18 INFO - "branch": "autoland", 16:04:18 INFO - "repo_path": "integration/autoland", 16:04:18 INFO - "revision": "e2525c03c65f1a0f81cd344e1aca57f22608ae42", 16:04:18 INFO - "stage_platform": "linux64", 16:04:18 INFO - "builduid": "50e4a1a8a2c1465fa3e9bcb56a802ffd", 16:04:18 INFO - "slavebuilddir": "test" 16:04:18 INFO - } 16:04:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:04:18 INFO - Running post-action listener: _resource_record_post_action 16:04:18 INFO - ##### 16:04:18 INFO - ##### Finished read-buildbot-config step (success) 16:04:18 INFO - ##### 16:04:18 INFO - ##### 16:04:18 INFO - ##### Running download-and-extract step. 16:04:18 INFO - ##### 16:04:18 INFO - Running pre-action listener: _resource_record_pre_action 16:04:18 INFO - Running main action method: download_and_extract 16:04:18 INFO - mkdir: /builds/slave/test/build/tests 16:04:18 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')]} 16:04:18 INFO - https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:04:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:04:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:04:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:21 INFO - Downloaded 1361 bytes. 16:04:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:04:21 INFO - Using the following test package requirements: 16:04:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:04:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:04:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:04:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'jsshell-linux-x86_64.zip'], 16:04:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:04:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:04:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:04:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:04:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:04:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:04:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:21 INFO - https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:04:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:04:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:04:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:25 INFO - Downloaded 22701709 bytes. 16:04:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:04:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:04:26 INFO - Return code: 0 16:04:26 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 16:04:26 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')]} 16:04:26 INFO - https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:04:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:04:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:04:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:32 INFO - Downloaded 22701709 bytes. 16:04:32 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'] 16:04:32 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 16:04:33 INFO - Return code: 0 16:04:33 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')]} 16:04:33 INFO - https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:04:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:04:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:04:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:35 INFO - Downloaded 14405245 bytes. 16:04:35 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'] 16:04:35 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 16:04:35 INFO - Return code: 0 16:04: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')]} 16:04:35 INFO - https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:04:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:04:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:04:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T-CKAbNES_eFRsGgoNfWCg/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 16:04:39 INFO - Downloaded 56477028 bytes. 16:04:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:04:39 INFO - mkdir: /builds/slave/test/properties 16:04:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:04:39 INFO - Writing to file /builds/slave/test/properties/build_url 16:04:39 INFO - Contents: 16:04:39 INFO - build_url:https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:04:40 INFO - Running post-action listener: _resource_record_post_action 16:04:40 INFO - Running post-action listener: set_extra_try_arguments 16:04:40 INFO - ##### 16:04:40 INFO - ##### Finished download-and-extract step (success) 16:04:40 INFO - ##### 16:04:40 INFO - ##### 16:04:40 INFO - ##### Running populate-webroot step. 16:04:40 INFO - ##### 16:04:40 INFO - Running pre-action listener: _resource_record_pre_action 16:04:40 INFO - Running main action method: populate_webroot 16:04:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:04:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:04:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:04:40 INFO - '--tppagecycles', 16:04:40 INFO - '7'], 16:04:40 INFO - 'tests': ['tsvgm']}}, 16:04:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:04:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:04:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:04:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:04:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['damp', 'tps']}, 16:04:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'tests': ['damp', 'tps']}, 16:04:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['dromaeo_dom']}, 16:04:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:04:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['basic_compositor_video']}, 16:04:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:04:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['a11yr', 16:04:40 INFO - 'ts_paint', 16:04:40 INFO - 'tpaint', 16:04:40 INFO - 'sessionrestore', 16:04:40 INFO - 'sessionrestore_no_auto_restore', 16:04:40 INFO - 'tabpaint']}, 16:04:40 INFO - 'other-e10s': {'tests': ['a11yr', 16:04:40 INFO - 'ts_paint', 16:04:40 INFO - 'tpaint', 16:04:40 INFO - 'sessionrestore', 16:04:40 INFO - 'sessionrestore_no_auto_restore', 16:04:40 INFO - 'tabpaint']}, 16:04:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:04:40 INFO - 'ts_paint', 16:04:40 INFO - 'tpaint', 16:04:40 INFO - 'sessionrestore', 16:04:40 INFO - 'sessionrestore_no_auto_restore', 16:04:40 INFO - 'tabpaint']}, 16:04:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:04:40 INFO - 'ts_paint', 16:04:40 INFO - 'tpaint', 16:04:40 INFO - 'sessionrestore', 16:04:40 INFO - 'sessionrestore_no_auto_restore', 16:04:40 INFO - 'tabpaint']}, 16:04:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['a11yr', 16:04:40 INFO - 'ts_paint', 16:04:40 INFO - 'tpaint', 16:04:40 INFO - 'sessionrestore', 16:04:40 INFO - 'sessionrestore_no_auto_restore', 16:04:40 INFO - 'tabpaint']}, 16:04:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['a11yr', 16:04:40 INFO - 'ts_paint', 16:04:40 INFO - 'tpaint', 16:04:40 INFO - 'sessionrestore', 16:04:40 INFO - 'sessionrestore_no_auto_restore', 16:04:40 INFO - 'tabpaint']}, 16:04:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['tsvgx', 16:04:40 INFO - 'tsvgr_opacity', 16:04:40 INFO - 'tart', 16:04:40 INFO - 'tscrollx', 16:04:40 INFO - 'cart']}, 16:04:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:04:40 INFO - 'tsvgr_opacity', 16:04:40 INFO - 'tart', 16:04:40 INFO - 'tscrollx', 16:04:40 INFO - 'cart']}, 16:04:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'talos_options': ['--disable-e10s'], 16:04:40 INFO - 'tests': ['tp5o']}, 16:04:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'tests': ['tp5o']}, 16:04:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'talos_options': ['--disable-e10s', 16:04:40 INFO - '--xperf_path', 16:04:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:40 INFO - 'tests': ['tp5n']}, 16:04:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:40 INFO - 'talos_options': ['--xperf_path', 16:04:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:40 INFO - 'tests': ['tp5n']}}, 16:04:40 INFO - 'talos.zip': {'path': '', 16:04:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:04:40 INFO - Running post-action listener: _resource_record_post_action 16:04:40 INFO - ##### 16:04:40 INFO - ##### Finished populate-webroot step (success) 16:04:40 INFO - ##### 16:04:40 INFO - ##### 16:04:40 INFO - ##### Running create-virtualenv step. 16:04:40 INFO - ##### 16:04:40 INFO - Running pre-action listener: _resource_record_pre_action 16:04:40 INFO - Running main action method: create_virtualenv 16:04:40 INFO - Creating virtualenv /builds/slave/test/build/venv 16:04:40 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 16:04:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:04:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:04:40 INFO - Using real prefix '/usr' 16:04:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:04:41 INFO - Installing distribute.............................................................................................................................................................................................done. 16:04:43 INFO - Installing pip.................done. 16:04:43 INFO - Return code: 0 16:04:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:04:43 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')]} 16:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:43 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')]} 16:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:43 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04:43 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 16:04:43 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 16:04:43 INFO - Using env: {'DISPLAY': ':0', 16:04:43 INFO - 'HOME': '/home/cltbld', 16:04:43 INFO - 'LANG': 'en_US.UTF-8', 16:04:43 INFO - 'LANGUAGE': 'en_US:en', 16:04:43 INFO - 'LOGNAME': 'cltbld', 16:04:43 INFO - 'MAIL': '/var/mail/cltbld', 16:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:43 INFO - 'MOZ_NO_REMOTE': '1', 16:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:43 INFO - 'NO_EM_RESTART': '1', 16:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:43 INFO - 'PWD': '/builds/slave/test', 16:04:43 INFO - 'SHELL': '/bin/bash', 16:04:43 INFO - 'SHLVL': '1', 16:04:43 INFO - 'TERM': 'linux', 16:04:43 INFO - 'TMOUT': '86400', 16:04:43 INFO - 'USER': 'cltbld', 16:04:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:43 INFO - '_': '/tools/buildbot/bin/python'} 16:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:43 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 16:04:43 INFO - Cleaning up... 16:04:43 INFO - Return code: 0 16:04:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:04:43 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')]} 16:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:43 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')]} 16:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:43 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04:43 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 16:04:43 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 16:04:43 INFO - Using env: {'DISPLAY': ':0', 16:04:43 INFO - 'HOME': '/home/cltbld', 16:04:43 INFO - 'LANG': 'en_US.UTF-8', 16:04:43 INFO - 'LANGUAGE': 'en_US:en', 16:04:43 INFO - 'LOGNAME': 'cltbld', 16:04:43 INFO - 'MAIL': '/var/mail/cltbld', 16:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:43 INFO - 'MOZ_NO_REMOTE': '1', 16:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:43 INFO - 'NO_EM_RESTART': '1', 16:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:43 INFO - 'PWD': '/builds/slave/test', 16:04:43 INFO - 'SHELL': '/bin/bash', 16:04:43 INFO - 'SHLVL': '1', 16:04:43 INFO - 'TERM': 'linux', 16:04:43 INFO - 'TMOUT': '86400', 16:04:43 INFO - 'USER': 'cltbld', 16:04:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:43 INFO - '_': '/tools/buildbot/bin/python'} 16:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:43 INFO - Downloading/unpacking psutil>=3.1.1 16:04:43 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 16:04:43 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 16:04:43 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 16:04:43 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 16:04:43 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 16:04:43 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 16:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:04:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:45 INFO - Installing collected packages: psutil 16:04:45 INFO - Running setup.py install for psutil 16:04:45 INFO - building 'psutil._psutil_linux' extension 16:04:45 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 16:04:46 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 16:04:46 INFO - building 'psutil._psutil_posix' extension 16:04:46 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 16:04:46 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 16:04:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:46 INFO - Successfully installed psutil 16:04:46 INFO - Cleaning up... 16:04:46 INFO - Return code: 0 16:04:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04: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')]} 16:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:04: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 mozsystemmonitor==0.3 16:04:46 INFO - Using env: {'DISPLAY': ':0', 16:04:46 INFO - 'HOME': '/home/cltbld', 16:04:46 INFO - 'LANG': 'en_US.UTF-8', 16:04:46 INFO - 'LANGUAGE': 'en_US:en', 16:04:46 INFO - 'LOGNAME': 'cltbld', 16:04:46 INFO - 'MAIL': '/var/mail/cltbld', 16:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:46 INFO - 'MOZ_NO_REMOTE': '1', 16:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:46 INFO - 'NO_EM_RESTART': '1', 16:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:46 INFO - 'PWD': '/builds/slave/test', 16:04:46 INFO - 'SHELL': '/bin/bash', 16:04:46 INFO - 'SHLVL': '1', 16:04:46 INFO - 'TERM': 'linux', 16:04:46 INFO - 'TMOUT': '86400', 16:04:46 INFO - 'USER': 'cltbld', 16:04:46 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:46 INFO - '_': '/tools/buildbot/bin/python'} 16:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 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 16:04:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:04:48 INFO - Installing collected packages: mozsystemmonitor 16:04:48 INFO - Running setup.py install for mozsystemmonitor 16:04:48 INFO - Successfully installed mozsystemmonitor 16:04:48 INFO - Cleaning up... 16:04:48 INFO - Return code: 0 16:04:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:48 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04:48 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 16:04:48 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 16:04:48 INFO - Using env: {'DISPLAY': ':0', 16:04:48 INFO - 'HOME': '/home/cltbld', 16:04:48 INFO - 'LANG': 'en_US.UTF-8', 16:04:48 INFO - 'LANGUAGE': 'en_US:en', 16:04:48 INFO - 'LOGNAME': 'cltbld', 16:04:48 INFO - 'MAIL': '/var/mail/cltbld', 16:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:48 INFO - 'MOZ_NO_REMOTE': '1', 16:04:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:48 INFO - 'NO_EM_RESTART': '1', 16:04:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:48 INFO - 'PWD': '/builds/slave/test', 16:04:48 INFO - 'SHELL': '/bin/bash', 16:04:48 INFO - 'SHLVL': '1', 16:04:48 INFO - 'TERM': 'linux', 16:04:48 INFO - 'TMOUT': '86400', 16:04:48 INFO - 'USER': 'cltbld', 16:04:48 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:48 INFO - '_': '/tools/buildbot/bin/python'} 16:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:48 INFO - Downloading/unpacking jsonschema==2.5.1 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 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 16:04:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:04:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:04: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 16:04: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 16:04: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 16:04: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 16:04: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 16:04: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 16:04:51 INFO - Downloading functools32-3.2.3-2.tar.gz 16:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:04:51 INFO - warning: no files found matching '*.txt' 16:04:51 INFO - no previously-included directories found matching 'build' 16:04:51 INFO - no previously-included directories found matching 'dist' 16:04:51 INFO - no previously-included directories found matching '.git*' 16:04:51 INFO - Installing collected packages: jsonschema, functools32 16:04:51 INFO - Running setup.py install for functools32 16:04:51 INFO - warning: no files found matching '*.txt' 16:04:51 INFO - no previously-included directories found matching 'build' 16:04:51 INFO - no previously-included directories found matching 'dist' 16:04:51 INFO - no previously-included directories found matching '.git*' 16:04:51 INFO - Successfully installed jsonschema functools32 16:04:51 INFO - Cleaning up... 16:04:51 INFO - Return code: 0 16:04:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:51 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04:51 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 16:04:51 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 16:04:51 INFO - Using env: {'DISPLAY': ':0', 16:04:51 INFO - 'HOME': '/home/cltbld', 16:04:51 INFO - 'LANG': 'en_US.UTF-8', 16:04:51 INFO - 'LANGUAGE': 'en_US:en', 16:04:51 INFO - 'LOGNAME': 'cltbld', 16:04:51 INFO - 'MAIL': '/var/mail/cltbld', 16:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:51 INFO - 'MOZ_NO_REMOTE': '1', 16:04:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:51 INFO - 'NO_EM_RESTART': '1', 16:04:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:51 INFO - 'PWD': '/builds/slave/test', 16:04:51 INFO - 'SHELL': '/bin/bash', 16:04:51 INFO - 'SHLVL': '1', 16:04:51 INFO - 'TERM': 'linux', 16:04:51 INFO - 'TMOUT': '86400', 16:04:51 INFO - 'USER': 'cltbld', 16:04:51 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:51 INFO - '_': '/tools/buildbot/bin/python'} 16:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:51 INFO - Downloading/unpacking blobuploader==1.2.4 16:04:51 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 16:04:51 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 16:04:51 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 16:04:51 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 16:04:51 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 16:04:51 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 16:04:53 INFO - Downloading blobuploader-1.2.4.tar.gz 16:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:04:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:04:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 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 16:04:53 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 16:04:54 INFO - Downloading docopt-0.6.1.tar.gz 16:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:04:54 INFO - Installing collected packages: blobuploader, requests, docopt 16:04:54 INFO - Running setup.py install for blobuploader 16:04:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:04:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:04:54 INFO - Running setup.py install for requests 16:04:54 INFO - Running setup.py install for docopt 16:04:54 INFO - Successfully installed blobuploader requests docopt 16:04:54 INFO - Cleaning up... 16:04:54 INFO - Return code: 0 16:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04: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')]} 16:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:54 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04:54 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 16:04:54 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 16:04:54 INFO - Using env: {'DISPLAY': ':0', 16:04:54 INFO - 'HOME': '/home/cltbld', 16:04:54 INFO - 'LANG': 'en_US.UTF-8', 16:04:54 INFO - 'LANGUAGE': 'en_US:en', 16:04:54 INFO - 'LOGNAME': 'cltbld', 16:04:54 INFO - 'MAIL': '/var/mail/cltbld', 16:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:54 INFO - 'MOZ_NO_REMOTE': '1', 16:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:54 INFO - 'NO_EM_RESTART': '1', 16:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:54 INFO - 'PWD': '/builds/slave/test', 16:04:54 INFO - 'SHELL': '/bin/bash', 16:04:54 INFO - 'SHLVL': '1', 16:04:54 INFO - 'TERM': 'linux', 16:04:54 INFO - 'TMOUT': '86400', 16:04:54 INFO - 'USER': 'cltbld', 16:04:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:54 INFO - '_': '/tools/buildbot/bin/python'} 16:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:55 INFO - Running setup.py (path:/tmp/pip-zPbuRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:55 INFO - Running setup.py (path:/tmp/pip-0kl6wL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:55 INFO - Running setup.py (path:/tmp/pip-sehoak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:55 INFO - Running setup.py (path:/tmp/pip-N3Tdhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:55 INFO - Running setup.py (path:/tmp/pip-x_nXRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:55 INFO - Running setup.py (path:/tmp/pip-jaBng5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:55 INFO - Running setup.py (path:/tmp/pip-LJuaxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:55 INFO - Running setup.py (path:/tmp/pip-dIAAW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:55 INFO - Running setup.py (path:/tmp/pip-Cc3a0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:55 INFO - Running setup.py (path:/tmp/pip-W_7dLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:55 INFO - Running setup.py (path:/tmp/pip-_OWnnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:55 INFO - Running setup.py (path:/tmp/pip-i8hFfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:55 INFO - Running setup.py (path:/tmp/pip-itvxvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:55 INFO - Running setup.py (path:/tmp/pip-Re9kVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:56 INFO - Running setup.py (path:/tmp/pip-OtpnSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:56 INFO - Running setup.py (path:/tmp/pip-m_MNeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:56 INFO - Running setup.py (path:/tmp/pip-foRygc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:04:56 INFO - Running setup.py install for manifestparser 16:04:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:04:56 INFO - Running setup.py install for mozcrash 16:04:56 INFO - Running setup.py install for mozdebug 16:04:56 INFO - Running setup.py install for mozdevice 16:04:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:04:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:04:56 INFO - Running setup.py install for mozfile 16:04:56 INFO - Running setup.py install for mozhttpd 16:04:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:04:56 INFO - Running setup.py install for mozinfo 16:04:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:04:56 INFO - Running setup.py install for mozInstall 16:04:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Running setup.py install for mozleak 16:04:57 INFO - Running setup.py install for mozlog 16:04:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Running setup.py install for moznetwork 16:04:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Running setup.py install for mozprocess 16:04:57 INFO - Running setup.py install for mozprofile 16:04:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Running setup.py install for mozrunner 16:04:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:04:57 INFO - Running setup.py install for mozscreenshot 16:04:57 INFO - Running setup.py install for moztest 16:04:57 INFO - Running setup.py install for mozversion 16:04:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:04:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:04:58 INFO - Cleaning up... 16:04:58 INFO - Return code: 0 16:04:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04: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')]} 16:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:58 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:04:58 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 16:04:58 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 16:04:58 INFO - Using env: {'DISPLAY': ':0', 16:04:58 INFO - 'HOME': '/home/cltbld', 16:04:58 INFO - 'LANG': 'en_US.UTF-8', 16:04:58 INFO - 'LANGUAGE': 'en_US:en', 16:04:58 INFO - 'LOGNAME': 'cltbld', 16:04:58 INFO - 'MAIL': '/var/mail/cltbld', 16:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:58 INFO - 'MOZ_NO_REMOTE': '1', 16:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:58 INFO - 'NO_EM_RESTART': '1', 16:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:58 INFO - 'PWD': '/builds/slave/test', 16:04:58 INFO - 'SHELL': '/bin/bash', 16:04:58 INFO - 'SHLVL': '1', 16:04:58 INFO - 'TERM': 'linux', 16:04:58 INFO - 'TMOUT': '86400', 16:04:58 INFO - 'USER': 'cltbld', 16:04:58 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:58 INFO - '_': '/tools/buildbot/bin/python'} 16:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:58 INFO - Running setup.py (path:/tmp/pip-IIZ92i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:58 INFO - Running setup.py (path:/tmp/pip-vd_rOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:58 INFO - Running setup.py (path:/tmp/pip-DySDID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:58 INFO - Running setup.py (path:/tmp/pip-3M91nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:58 INFO - Running setup.py (path:/tmp/pip-rEkOv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:58 INFO - Running setup.py (path:/tmp/pip-j36kJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:58 INFO - Running setup.py (path:/tmp/pip-A5k7oS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:58 INFO - Running setup.py (path:/tmp/pip-mF2i9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:58 INFO - Running setup.py (path:/tmp/pip-xtcy26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:58 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)) 16:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:58 INFO - Running setup.py (path:/tmp/pip-T2UZsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:59 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:59 INFO - Running setup.py (path:/tmp/pip-pkymVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:59 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:59 INFO - Running setup.py (path:/tmp/pip-8BI7Kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:59 INFO - Running setup.py (path:/tmp/pip-bXwxww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:59 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:59 INFO - Running setup.py (path:/tmp/pip-F8oP7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:59 INFO - Running setup.py (path:/tmp/pip-XiterK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:59 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:59 INFO - Running setup.py (path:/tmp/pip-FBCSg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:59 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)) 16:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:59 INFO - Running setup.py (path:/tmp/pip-L_21NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:59 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)) 16:04:59 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)) 16:04:59 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)) 16:04:59 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)) 16:04:59 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)) 16:04:59 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)) 16:04:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:04:59 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 16:04:59 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 16:04:59 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 16:04:59 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 16:04:59 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 16:04:59 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 16:05:01 INFO - Downloading blessings-1.6.tar.gz 16:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:05:01 INFO - Installing collected packages: blessings 16:05:01 INFO - Running setup.py install for blessings 16:05:01 INFO - Successfully installed blessings 16:05:01 INFO - Cleaning up... 16:05:01 INFO - Return code: 0 16:05:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:05:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:05:01 INFO - Reading from file tmpfile_stdout 16:05:01 INFO - Current package versions: 16:05:01 INFO - argparse == 1.2.1 16:05:01 INFO - blessings == 1.6 16:05:01 INFO - blobuploader == 1.2.4 16:05:01 INFO - docopt == 0.6.1 16:05:01 INFO - functools32 == 3.2.3-2 16:05:01 INFO - jsonschema == 2.5.1 16:05:01 INFO - manifestparser == 1.1 16:05:01 INFO - mozInstall == 1.12 16:05:01 INFO - mozcrash == 0.17 16:05:01 INFO - mozdebug == 0.1 16:05:01 INFO - mozdevice == 0.48 16:05:01 INFO - mozfile == 1.2 16:05:01 INFO - mozhttpd == 0.7 16:05:01 INFO - mozinfo == 0.9 16:05:01 INFO - mozleak == 0.1 16:05:01 INFO - mozlog == 3.2 16:05:01 INFO - moznetwork == 0.27 16:05:01 INFO - mozprocess == 0.22 16:05:01 INFO - mozprofile == 0.28 16:05:01 INFO - mozrunner == 6.11 16:05:01 INFO - mozscreenshot == 0.1 16:05:01 INFO - mozsystemmonitor == 0.3 16:05:01 INFO - moztest == 0.7 16:05:01 INFO - mozversion == 1.4 16:05:01 INFO - psutil == 3.1.1 16:05:01 INFO - requests == 1.2.3 16:05:01 INFO - wsgiref == 0.1.2 16:05:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:05: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')]} 16:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05: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')]} 16:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:01 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:05:01 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 16:05:01 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 16:05:01 INFO - Using env: {'DISPLAY': ':0', 16:05:01 INFO - 'HOME': '/home/cltbld', 16:05:01 INFO - 'LANG': 'en_US.UTF-8', 16:05:01 INFO - 'LANGUAGE': 'en_US:en', 16:05:01 INFO - 'LOGNAME': 'cltbld', 16:05:01 INFO - 'MAIL': '/var/mail/cltbld', 16:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:01 INFO - 'MOZ_NO_REMOTE': '1', 16:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:01 INFO - 'NO_EM_RESTART': '1', 16:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:01 INFO - 'PWD': '/builds/slave/test', 16:05:01 INFO - 'SHELL': '/bin/bash', 16:05:01 INFO - 'SHLVL': '1', 16:05:01 INFO - 'TERM': 'linux', 16:05:01 INFO - 'TMOUT': '86400', 16:05:01 INFO - 'USER': 'cltbld', 16:05:01 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:01 INFO - '_': '/tools/buildbot/bin/python'} 16:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 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)) 16:05:02 INFO - Cleaning up... 16:05:02 INFO - Return code: 0 16:05:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:02 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 0x2708030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', '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 16:05:02 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 16:05:02 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 16:05:02 INFO - Using env: {'DISPLAY': ':0', 16:05:02 INFO - 'HOME': '/home/cltbld', 16:05:02 INFO - 'LANG': 'en_US.UTF-8', 16:05:02 INFO - 'LANGUAGE': 'en_US:en', 16:05:02 INFO - 'LOGNAME': 'cltbld', 16:05:02 INFO - 'MAIL': '/var/mail/cltbld', 16:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:02 INFO - 'MOZ_NO_REMOTE': '1', 16:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:02 INFO - 'NO_EM_RESTART': '1', 16:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:02 INFO - 'PWD': '/builds/slave/test', 16:05:02 INFO - 'SHELL': '/bin/bash', 16:05:02 INFO - 'SHLVL': '1', 16:05:02 INFO - 'TERM': 'linux', 16:05:02 INFO - 'TMOUT': '86400', 16:05:02 INFO - 'USER': 'cltbld', 16:05:02 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:02 INFO - '_': '/tools/buildbot/bin/python'} 16:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:05:02 INFO - Cleaning up... 16:05:02 INFO - Return code: 0 16:05:02 INFO - Running post-action listener: _resource_record_post_action 16:05:02 INFO - Running post-action listener: _start_resource_monitoring 16:05:02 INFO - Starting resource monitoring. 16:05:02 INFO - ##### 16:05:02 INFO - ##### Finished create-virtualenv step (success) 16:05:02 INFO - ##### 16:05:02 INFO - ##### 16:05:02 INFO - ##### Running install step. 16:05:02 INFO - ##### 16:05:02 INFO - Running pre-action listener: _resource_record_pre_action 16:05:02 INFO - Running main action method: install 16:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:05:02 INFO - Reading from file tmpfile_stdout 16:05:02 INFO - Detecting whether we're running mozinstall >=1.0... 16:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:05:02 INFO - Reading from file tmpfile_stdout 16:05:02 INFO - Output received: 16:05:02 INFO - Usage: mozinstall [options] installer 16:05:02 INFO - Options: 16:05:02 INFO - -h, --help show this help message and exit 16:05:02 INFO - -d DEST, --destination=DEST 16:05:02 INFO - Directory to install application into. [default: 16:05:02 INFO - "/builds/slave/test"] 16:05:02 INFO - --app=APP Application being installed. [default: firefox] 16:05:02 INFO - mkdir: /builds/slave/test/build/application 16:05:02 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'] 16:05:02 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 16:05:18 INFO - Reading from file tmpfile_stdout 16:05:18 INFO - Output received: 16:05:18 INFO - /builds/slave/test/build/application/firefox/firefox 16:05:18 INFO - Running post-action listener: _resource_record_post_action 16:05:18 INFO - ##### 16:05:18 INFO - ##### Finished install step (success) 16:05:18 INFO - ##### 16:05:18 INFO - ##### 16:05:18 INFO - ##### Running run-tests step. 16:05:18 INFO - ##### 16:05:18 INFO - Running pre-action listener: _resource_record_pre_action 16:05:18 INFO - Running main action method: run_tests 16:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:05:18 INFO - Python 2.7.3 16:05:18 INFO - Return code: 0 16:05:18 INFO - grabbing minidump binary from tooltool 16:05:18 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')]} 16:05:18 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 0x29c73b0>, '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 0x29c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:05:18 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 16:05:18 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 16:05:18 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 16:05:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:05:19 INFO - Return code: 0 16:05:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:05:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:05:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:05:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:05:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:05:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 16:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 16:05:19 INFO - Using env: {'DISPLAY': ':0', 16:05:19 INFO - 'HOME': '/home/cltbld', 16:05:19 INFO - 'LANG': 'en_US.UTF-8', 16:05:19 INFO - 'LANGUAGE': 'en_US:en', 16:05:19 INFO - 'LOGNAME': 'cltbld', 16:05:19 INFO - 'MAIL': '/var/mail/cltbld', 16:05:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:19 INFO - 'MOZ_NO_REMOTE': '1', 16:05:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:19 INFO - 'NO_EM_RESTART': '1', 16:05:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:19 INFO - 'PWD': '/builds/slave/test', 16:05:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:19 INFO - 'SHELL': '/bin/bash', 16:05:19 INFO - 'SHLVL': '1', 16:05:19 INFO - 'TERM': 'linux', 16:05:19 INFO - 'TMOUT': '86400', 16:05:19 INFO - 'USER': 'cltbld', 16:05:19 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835', 16:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:19 INFO - '_': '/tools/buildbot/bin/python'} 16:05:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 16:05:19 INFO - mozversion application_buildid: 20160629144828 16:05:19 INFO - mozversion application_changeset: e2525c03c65f1a0f81cd344e1aca57f22608ae42 16:05:19 INFO - mozversion application_display_name: Nightly 16:05:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:05:19 INFO - mozversion application_name: Firefox 16:05:19 INFO - mozversion application_remotingname: firefox 16:05:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:05:19 INFO - mozversion application_vendor: Mozilla 16:05:19 INFO - mozversion application_version: 50.0a1 16:05:19 INFO - mozversion platform_buildid: 20160629144828 16:05:19 INFO - mozversion platform_changeset: e2525c03c65f1a0f81cd344e1aca57f22608ae42 16:05:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:05:19 INFO - mozversion platform_version: 50.0a1 16:05:19 INFO - using testdate: 1467241519 16:05:19 INFO - actual date: 1467241519 16:05:19 INFO - starting webserver on 'localhost:48746' 16:05:19 INFO - SUITE-START | Running 2 tests 16:05:19 INFO - TEST-START | dromaeo_css 16:05:19 INFO - operating with platform_type : linux_ 16:05:19 INFO - Initialising browser for dromaeo_css test... 16:05:19 INFO - TEST-INFO | started process 28431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNfeipo/profile http://localhost:48746/getInfo.html) 16:05:23 INFO - PROCESS | 28431 | __metrics Screen width/height:1600/1200 16:05:23 INFO - PROCESS | 28431 | colorDepth:24 16:05:23 INFO - PROCESS | 28431 | Browser inner width/height: 1024/697 16:05:23 INFO - PROCESS | 28431 | __metrics 16:05:25 INFO - PROCESS | 28431 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:05:25 INFO - TEST-INFO | 28431: exit 0 16:05:25 INFO - Browser initialized. 16:05:25 INFO - Running cycle 1/1 for dromaeo_css test... 16:05:25 INFO - TEST-INFO | started process 28515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNfeipo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:38 INFO - PROCESS | 28515 | RSS: Main: 159186944 16:05:38 INFO - PROCESS | 28515 | 16:06:29 INFO - PROCESS | 28515 | 1467241589568 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] 16:08:40 INFO - PROCESS | 28515 | Cycle 1(1): loaded http://localhost:48746/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48746/tests/dromaeo/cssquery-ext.html) 16:08:42 INFO - PROCESS | 28515 | RSS: Main: 272785408 16:08:42 INFO - PROCESS | 28515 | 16:11:44 INFO - PROCESS | 28515 | Cycle 1(1): loaded http://localhost:48746/tests/dromaeo/cssquery-ext.html (next: http://localhost:48746/tests/dromaeo/cssquery-jquery.html) 16:11:45 INFO - PROCESS | 28515 | RSS: Main: 248471552 16:11:45 INFO - PROCESS | 28515 | 16:14:47 INFO - PROCESS | 28515 | Cycle 1(1): loaded http://localhost:48746/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48746/tests/dromaeo/cssquery-mootools.html) 16:14:48 INFO - PROCESS | 28515 | RSS: Main: 249937920 16:14:48 INFO - PROCESS | 28515 | 16:17:50 INFO - PROCESS | 28515 | Cycle 1(1): loaded http://localhost:48746/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48746/tests/dromaeo/cssquery-prototype.html) 16:17:50 INFO - PROCESS | 28515 | RSS: Main: 242511872 16:17:50 INFO - PROCESS | 28515 | 16:20:54 INFO - PROCESS | 28515 | Cycle 1(1): loaded http://localhost:48746/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48746/tests/dromaeo/cssquery-yui.html) 16:20:54 INFO - PROCESS | 28515 | RSS: Main: 231682048 16:20:54 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | Cycle 1(1): loaded http://localhost:48746/tests/dromaeo/cssquery-yui.html (next: http://localhost:48746/tests/dromaeo/cssquery-dojo.html) 16:23:57 INFO - PROCESS | 28515 | RSS: Main: 279318528 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | __start_tp_report 16:23:57 INFO - PROCESS | 28515 | _x_x_mozilla_page_load 16:23:57 INFO - PROCESS | 28515 | _x_x_mozilla_page_load_details 16:23:57 INFO - PROCESS | 28515 | |i|pagename|runs| 16:23:57 INFO - PROCESS | 28515 | |0;dojo.html;2595.5717306644397;2633.1705512763465;2660.1886424640484;2665.3869609989715;2673.3049407154135;4658.860234192971;4702.529747025295;4714.320532014904;4799.544043315884;4858.174110401232;4752.097101550703;4763.8809029774275;4771.8807029824275;4772.284157376395;4800.615950723943;4342.761148136851;4364.563543645641;4394;4422.845200417986;4449.132419178261;27.18045759504559;4420.248557745175;4446.333050042487;4460.710053846506;4484.686071974963;4347.021920067991;4403.119376124756;4409.735415875058;4410.492793328776;4437.423134992447;4398.296272596369;4398.714083584557;4406.272964960777;4407.867763967086;4446.244138496463;4254.170436764832;4345.348197770328;4347.021920067991;4360.236958532276;4404.801783919731;4678.251479763221;4679.391679081732;4725.763711814396;4742.430908290993;4799;5101.234814777776;5109.718965456898;5112.616553758446;5114.744262786846;5123.538881500645;4914.11545921734;5084.949150508484;5108.055009823194;5145.382554093495;5151.5878729701535;4716.292556116576;4773.236282194867;4774.809007639725;4808.2547205183255;4819.783109759999;5310.707911064851;5311.787528498817;5313.628046036741;5321.201819727072;5327.547158491494;4855.344528488608;4912.484189160158;5071.264666623319;5077.822276220315;5085.593152547787;5039.874003149951;5072;5079.720615366116;5085.974570127126;5090.312807770978;5034.270030845507;5038.6976781392505;5038.974805125951;5047.621428392812;5089.847304580869;4949.7030178189425;5000.549950504472;5012.473690262543;5014.373203349582;5021.824236151717;4259.084296876186;4261.10516791471;4266.743995360288;4307;4311.374850646639;3778.4521244419957;3780.8298626561873;3790.450384694204;3812.599677033927;3823.292690852146;4273;4349.9130017399475;4468.32975053738;4483.260262056724;4515.525869783691;4972.8508144755715;4995.600351971833;5006.574441172468;5035.496450355008;5036.84889453317;4335.3713711511655;4341.891452713708;4343.066240758252;4348.816254591932;4392.582704643054;4506.81972721094;4511.796969136397;4531.274996000624;4562.726236425825;4572.428446444194;4751.596114330251;4783.521647835189;4797.4482934462285;4804.5195480451675;4807.639427043056;5063.949360506348;5065.594752419871;5070.796811168894;5072.1123803335;5115.820946266863;4723.433188017322;4763.476017638126;4793.400824896888;4814.470408255019;4845.709257444564;5002.099622067917;5015.297858299914;5047.722375269395;5048.7223202722735;5060.240963855451;5129.820456284012;5140.4859514049795;5147.202183661539;5149.407818100966;5149.510796474329;5001.824936127218;5006.899862002667;5007.048660754445;5010.574101201439;5052.040112378636;4762.880927976828;4894.510548945077;4953.0589188054155;4960.677555958851;4985.70085794839;4876.829310974099;4880.487548807466;4893.21708526634;4916.852494425173;4953.430355509163;4798.4001999750035;4979.975100124476;4986.825461108844;4988.12707776145;5029.346184995927;4918.803247870045;4948.38145231846;4963.280324353021;4971.229459433794;4985.700857948535 16:23:57 INFO - PROCESS | 28515 | |1;ext.html;568.0712035821432;578.1183694865327;586.4780345492511;590.8581940334321;600.3036477685886;27067.864660676692;39528.209435811266;40321;40626.18747625053;43818.12363752731;41715.58284417155;41968.58031419685;42001.78999105008;42093.7895310523;42100.578994210045;7554.169041405441;7864.960675196616;8007.95996020022;8014.639341229629;8017.198280171965;7520.135184453776;7742.341900938427;7756.301932826044;10125.341852779582;10413.323133996304;10465.372077675325;10497.4226417547;10503.264771466;10515.73710657232;10530.894691053069;10249.487525623726;10302.690919272434;10314.071733543942;10314.432706201193;10328.896711032943;7475.102987641463;9873.259505537042;10268.35706286998;10279.897201027969;10639.095676867399;38829;39055.02362440933;39153.41269880954;39193.60806391928;39203;3001.864916078782;3072.800267982574;3117.984410077958;3142.213444227178;3192.8882489113003;29620.851895740383;29652.110436686933;29909.25226869324;30042;30169.547456788165;3624.9456258156147;3629.201575653352;3632.6185750496347;3642.908927276813;3656.305301992613;250986.99999999817;251739;252319;252414;252497;52050;52462.85222886639;52780.20829687558;52839;52853;33428.3314333712;34497;34576.677915260596;36099.819500902326;55061.449385506436;15088.24558772079;15147.242637868063;15181.62045948838;15203.771943420857;15220;10471.84292235617;11578.42107894602;11676.532938682527;11697.23967942081;11698.766024679458;17967.460976170474;18508.81491185098;18513.44459666212;18977.24091036343;19031.619367612846;6876;6917.827054323682;6976.825579360455;6982.546134501241;6988.336108069725;788.712120076168;869.7086476030601;927.679950417105;990.8860481044773;1026.958921643126;27731.44537109287;28426;28439;28503.572446412905;28615.28461788472;917.655879045358;957.5116690487762;982.5873133284038;985.2019863910256;1001.4942454060798;15265.313060912285;15277;15293.6176595586;15452;15549.30028148736;416536;626858;627344;630281;635938;50845.23732144021;50892.236616450784;51418.74290628598;51439.22841157386;51444.74277628588;1083.7507373304027;1193.773183095209;1215.0583297943988;1219.8594314316142;1223.5105957616847;25461.87269063717;29060.418791623626;29071.854640727506;29549;29774;41663.16673666449;42291.71353580803;42335.7883210582;42369.36445953252;42424.57575424206;329721;332247;332411;332711;333631;24843.254702358958;26077.217683469527;26086;26086.086986956125;26087.347816304744;41527.58472415237;41680.58319416767;41995;43033.784831075645;43039.354409683256;35553.28893422169;36224.818875905454;36548.26903461969;36751.632483674824;36915.44626830546;35423.64576354204;35518.289634207686;35881.82059089688;35884.026378548384;35993.820030899675;34815.259986987076;35665.82167089252;35746.463803042454;36190.09524761902;36317.45523817198;30089;32490.512642310856;32716;32897.342053158325;33098.83450582732;24278.94705282736;24783.752162478144;25137.120200792884;25402.110926117497;25490.362740931625 16:23:57 INFO - PROCESS | 28515 | |2;jquery.html;344.8723972130312;368.0779646984305;379.8594520027592;380.61938061938054;389.2953753705793;13181.406836692347;16274.43039493618;16343.182840857955;16770.58073548162;17588.12059397028;17232.310707571683;17560.385386511476;18169.09154542271;18487.90756046218;18667.439976800717;5005.370032719728;5124.8975020499565;5246.055709972204;5251.527362537371;5253.658512196695;3755.380362240227;5149.639525233235;5177.58579313654;5786.305643322808;6156.815295541141;6198.721057552421;6215.440610345068;6218.657973811439;6225.408586184305;6252.374762523734;5962.552808539355;6167.198264225667;6172.382761723837;6173.32093469718;6175.59858609189;3414.3854106260865;3608.0799396154075;3609.3864043112735;5412.5940554458575;5418.756155972991;19333;21150;21365.893170534204;22917.88541057284;23006.309810705705;1905.4664693885734;2019.8081182287806;2373.3947843299848;2487.8880450379775;2547.210364786922;13691.452341906312;13736.10715303502;13790.103643263155;13893.722125557431;14484.275786210648;1686.5109118355786;1698.6687595918802;1710.3586155191804;1782.7236778299384;1812.2207450796152;68770;68904.6554767228;69338;69679.30320696779;70139.29860701378;8847.778805529913;9333.673321433716;9680.612775489042;9927.453990030474;10337.328073675337;12386.690332741753;13224.47102115924;13249.93375033138;13667.111637743516;13744.862551374557;7486.438517111238;7708.5760283183845;7714.9228507715325;7722.652480638385;7876.369890408753;7497.2877576630135;7521.699132034767;7672.5012874163;7684.923150768533;7690.461667683208;6236.345183755731;6352.523560732963;6363.459105975951;6376.904346434807;6422.068800023971;1719.6388758360633;1768.51365874383;1773.202059073422;1811.610503741702;1860.4139695995718;874.4447275979799;882.8808110904946;917.7109210598641;922.7093465558328;932.9440233586007;16902.74645880313;17298.481045568653;17835.197416116305;18586.07069646512;18605.90697046506;862.3532350736947;883.4875772052194;917.1378903830378;934.4066517761134;951.4909523404945;7898.60506974649;10408.115310198567;10472.476376181314;10493.370397776005;10553.525091370906;390071;401756;405588;406480;406928;67280.32719672838;68450;69172.65413672899;69432.3056769436;70047.64976175189;899.4243684042218;917.206616276929;927.6521304510809;934.0379409208526;937.8124375125002;16448.17759112016;16510.75233871469;16957.236924338682;17082.658346833243;17153;39336.213275734895;39633.40549891812;40911.38632920449;41039.17921641491;41486.58513414827;54374.184387235015;56801.431985679614;57187.714061429426;57412.1388179186;57902.7104864473;7326.194118647051;7347.963260183665;7469.327760501581;7506.3244308012545;7508.249175082448;10420;11048.392338421463;11551.364674942959;11605.593804216813;11636.767264654829;9870.703878883645;9882.061204185591;9915.058069483388;9926.255530835071;10190.133838623504;9806.852897206405;9993.350432221881;10009.749756256151;10015.399076055168;10049;9821.870484894043;9838.163756080814;9989.800203996025;10035.24735644844;10317.226208034577;7826.882596760939;7891.289783919475;8019.157988411366;8065.072516660424;8071.43499955021;7510.8497830042;9617.71146865595;9646.565904534453;9650.71047868565;10014.383315354446 16:23:57 INFO - PROCESS | 28515 | |3;mootools.html;1253.1603825436957;1377.6418131285868;1427.5717284814557;1502.8872834537412;1736.9826301736978;6973.790786276407;7676.38588912887;7964.482308649948;7977;8201.05687845898;7805.687772489094;8018.398620103487;8026.879596806038;8037.115917249098;8040.597970101486;2230.0856648774006;2277.635578307471;2279.851809632377;2280.0195915756217;2281.5208806150727;2195.538936823269;2218.866867987918;2233.475133343663;2242.439390152462;2246.539459410817;2194.0346247651064;2210.8120809731113;2217.84475086744;2218.0794970087395;2218.1238410827714;2144.9892750536305;2169.858959167657;2171.185805323004;2172.7935846094597;2173.1307477009163;2161.492049368397;2163.0698799516204;2167.967480487794;2169.761326254111;2171.815395691368;7759.185285544993;7997.400194985399;8016.67933282668;8035.919640803576;8109.878351824727;4623.976880115578;4715.693479923794;4737.692050016738;4740.620750339965;4746.738929358884;2798.5802129680505;2874.841883696396;2907.985460072707;2916.47503449379;2926.312316605596;925.8935222449437;942.2085448223456;942.7972985808016;949.2690628216235;953.4660590069514;11009.394483303415;11132.220744547885;11137.387443690593;11145.21983461158;11156.219064665476;3899;4023.350426325251;4141.088960428701;4147.066909945238;4169.4162817205615;8577.699780507652;8605.053444121142;8656.913430865736;8696.608652610648;8711.738647840575;4638.420197475316;4639.164950308977;4647.674662773641;4653.371794807657;4656.511066338054;3554.289142171576;3791.962080379216;3911.413288006765;3916.6083391661177;4276.850310239127;3263.0537144228383;3280.9343813124083;3295.126791400281;3305.2397948471985;3316;983.7393090830935;992.925530585209;993.1508560181086;997.1225321716843;1007.2395341517108;701.2881924846286;718.4683334332557;765.0589774577301;779.2052106851094;822.2188920525525;5036.597072234213;5072.087024335655;5189.481051894856;5214.113600687892;5314.415414304423;393.95272567291534;399.3052089364485;399.8780371986514;401.6204686571162;413.2912055824276;3492.2840817632323;3554.2180720241504;3617.6744093031753;3634.2913131939545;3656.8171591420323;9496.572654230577;9891.208703303862;9997.250206234561;10010.399376037461;10031.247656425798;9907.603695852085;9937.354071985297;9951.203903687687;9972.501374931224;9992.400455972665;6734.427573656196;6991.510594258353;6994.860102797916;7188.17336006366;7194.676239569232;5581.832545023656;5717.199592057199;5785.810585595764;5789.9710501447225;5868.647881127146;9315.115064068903;9523.19052880513;9534.332596718163;9545.474998875128;9572.76068098303;9372.109649583279;9505.19205867509;9563.665271715456;9669.516524173623;9691.370060946016;6109.480694141048;6801.965990170018;6945.0624165739;6980.616066116412;7205.207427182901;7580;7614.657340419583;7615.847683046419;7623.771286861403;7663.080430348394;6879.312068793281;6967.686454109678;7048.295170483116;7104.680289387094;7116.288371163049;6915.965420172867;7019.3682568569075;7029.437644988491;7069.328413800681;7103.893441598277;5955.523558115254;6027;6030.306514750684;6030.7286172121485;6038.12447195163;4394.560543945579;4587.243104887656;4607.401037865056;4628.583427491407;4718.929216061693;4585.5414458555215;4615.469221039623;4627.8611641650805;4635.629149668086;4636.235021221461 16:23:57 INFO - PROCESS | 28515 | |4;prototype.html;298.99551506727397;304.092284530676;304.8750012494877;309.6516419028593;311.3197663106112;4884.485809399089;4900.5099490051025;4922.1632322505175;4932.753362331887;4960;4827.468978412372;4831.227003679412;4860.100881336955;4874.341963834881;4882.6093912487;3638.181409182926;3714.312852122366;3729.9813500932455;3823.1971286029966;3826;3795.525559305087;3813.809309534526;3815.2369526094753;3820.2550502651993;3830.636089571486;3620.8008559529217;3785.9432108518395;3791.867284645038;3792.8672496462627;3793.7344385892998;3671.889843304705;3783.224438990028;3786.318462676717;3789.280036793028;3790.3935370340887;3357.992602219349;3361.4117529432497;3370.747193960463;3372.4266874631126;3382.5624109753257;4748.833790817304;4760.0721707708435;4767.451743049524;4767.737774422405;4769.451513075971;4276.465441819772;4326.697131200817;4369.672274579388;4372.737635741865;4391.890202744925;5027.924581131249;5034.421041580228;5054.62090343222;5089.185730283174;5111.08000559899;3681.9263614727824;3681.9815900920325;3690.3910854708943;3695.427208782616;3704.666580007812;5132.178851383798;5255.9488102380055;5274.340707411574;5280.9207861882105;5297.390800058004;3770.6606405423645;3773.566039905446;3778.5465744110884;3778.6599206071587;3787.9242415151543;3844.615538446133;3851.30676478231;3851.5955824638568;3854.5374555053572;3854.8990800014058;4321.503027151918;4356.6732495063;4360.062586543845;4362.30203167498;4364.607185353333;4855.6601037927;5040.445550989388;5059.418166910852;5065.6200784941275;5065.645404821627;1296.708240645866;1321.9886786608251;1327.4955516903708;1331.2811082015603;1341.4030756313348;865.9393842431095;868.769776009358;892.8571428571528;895.2122132523468;896.0815164456484;4337.479502459725;4376.912461750747;4520.773961301988;4521.62090562375;4529.5243999380245;5238.973805131026;5242.318498595235;5242.947570524322;5267.578593712494;5275.947240527622;4297.935530966975;4552.066826300666;4556.476005259371;4563.155816173952;4598.655100867449;2785.1366076516347;2804.1587523743206;2821.4498172856506;2826.770354895627;2833.504136776056;4969.503049695001;4971.353724015927;4977.452480227172;5009.348784657971;5019.548240658285;5115.672369072064;5371.516563509303;5379.67721936685;5437.755301011385;5446.673199608036;4985.177445721488;4994.300797888372;5009.574186194214;5013.924791128136;5032.823851165192;5305.495977882095;5308.416074231909;5321.494458026481;5354.3842458117815;5363.356397232357;5098.260752190986;5123.280790651685;5186.118359878905;5211.166213405837;5212.374515058217;4983.800647974185;5017.795729025081;5034.974825125851;5037.949620503748;5053.36832895888;3732.5520937487677;3748.8500459981296;3754.737168398186;3765.6987441004107;3773.716971227114;5129.717865517432;5295.4969277918535;5319.601029922694;5320.414754377093;5345.705986170798;5127.948720512747;5154.525827602697;5154.716490592903;5160.200168973815;5173.689578625294;5131.923021154611;5169.560587350117;5173.430922598586;5178.456262092426;5182.740862956762;4819.349387832598;4939.975300123477;5031.471695472069;5032.496750324938;5055.407546622802;5049.469805670352;5058.317127187783;5060.924086138637;5074.264231686311;5087.160618498055;4934.925976110433;5039.244113383022;5055.34280543527;5063.392392912874;5064.974675126601 16:23:57 INFO - PROCESS | 28515 | |5;yui.html;646.476354153136;650.6649075726002;683.9521233513656;691.5263044814302;720.5388551327148;11551.133664975128;11911.642650720492;12381.195222310544;12395.008399328055;12540.93729531351;11786.410679466015;11813.645590632295;11878.227915185507;11897.226680265798;11910.285382877033;629.351767679291;766.9846603067949;768.4390395011645;784.3764207455058;785.6464590934074;797.1271457753751;806.1132753970614;806.705552473346;806.7297455352464;807.4549678966704;841.5750046226647;841.6338892581716;844.3625063077368;844.5059640110543;845.7378212754035;662.9303923088103;777.5101685937823;815.8735396013627;838.2916435611899;838.995805020974;602.3043384890415;602.8312072619674;602.8312072619674;603.4357875386484;603.9335673075958;11184.38485883276;11206.607768728056;11212.887871121266;11221.719457013558;11239.494222759995;11874.584389546411;12213.938930305381;12286.508539658404;12330.568430104993;12526.311052892088;10681.946590267076;10726.517306721138;10815.729606759895;10953.233273670847;10995.670129896116;3890.396988466792;3944.4872166618156;3952.743071700359;3956.742811717229;4039.0104424415913;58876.41123588795;59001.7049914752;59021.11468327978;59041;59631.40368596302;9292.488913109708;9390.85913711295;9535.094166054214;9744.692046881783;9895.554700038516;16421.42525011619;16426.75359869603;16465.6706865862;16597.50207493753;16658.833411665728;6718.32816718334;6778.423833974168;6833.974903764376;6932.994715766185;7022.297770222936;3785.1294202333615;3807.314683357022;3833.1375440526217;3846.4986105992893;3850.4609354690365;2737.575675770259;2833.773298136144;2843.6018957345987;2844.8008639395457;2845.743883050494;486.95130486951024;492.7388484103431;494.0489557601674;531.388902761827;556.0380541662946;526.3052770343187;596.9462748352669;598.7664810723821;611.2756383685347;618.0636335951037;8148.592570371457;8287.461315014505;8325.959255093114;8478.236958673626;8931.955340223258;518.2304278146948;567.758702551414;604.3679774581634;629.2480485819402;641.8363317354044;3689.2805902849223;3862.343401621731;3953.0710283083454;3994.820233089518;4015.558288588253;74055;76444.61777691076;76766;89528;91779;78513.21486785059;82593;83852.90367740733;84577.57711211528;84583.15416845876;69867.65066174738;71687;72302.63848680828;72596.63701681458;75003.24996750071;8046.597670116354;8240.340772738047;8367.496484661357;8498.617562209593;8603.956980215058;41565;42307;42630;42719;44592.33111503265;58485;60994.69502652636;61580.384196159255;62763;63617;10533.894661053291;11001.22991390595;11014.724631884266;11087.33475991443;11121.83317250258;12347.259164450163;12711.427985740478;12735.108542401942;12749.362531873554;13515.324233788468;10671.253012289375;10845.240833141605;10939.945300273448;11051.060659843683;11138.33170009802;11052.795908354488;11183;11535.134864885335;11601.709957251136;11805.881941180478;11389.487473063566;11923.105767067678;12002.459889304828;12063.21589096706;12071.879281207075;9890.950545247515;10037;10255.743606409602;10435.426051567236;10449.21630877672;9158.40470369424;9295.395799273025;9536.904630953602;9543.570539325608;9565.186959108554 16:23:57 INFO - PROCESS | 28515 | __end_tp_report 16:23:57 INFO - PROCESS | 28515 | __start_cc_report 16:23:57 INFO - PROCESS | 28515 | _x_x_mozilla_cycle_collect,1040 16:23:57 INFO - PROCESS | 28515 | __end_cc_report 16:23:57 INFO - PROCESS | 28515 | __startTimestamp1467242637790__endTimestamp 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | ------- Summary: start ------- 16:23:57 INFO - PROCESS | 28515 | Number of tests: 6 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | [#0] dojo.html Cycles:165 Average:4678.56 Median:4808.25 stddev:613.06 (12.8%) stddev-sans-first:592.77 16:23:57 INFO - PROCESS | 28515 | Values: 2595.6 2633.2 2660.2 2665.4 2673.3 4658.9 4702.5 4714.3 4799.5 4858.2 4752.1 4763.9 4771.9 4772.3 4800.6 4342.8 4364.6 4394.0 4422.8 4449.1 27.2 4420.2 4446.3 4460.7 4484.7 4347.0 4403.1 4409.7 4410.5 4437.4 4398.3 4398.7 4406.3 4407.9 4446.2 4254.2 4345.3 4347.0 4360.2 4404.8 4678.3 4679.4 4725.8 4742.4 4799.0 5101.2 5109.7 5112.6 5114.7 5123.5 4914.1 5084.9 5108.1 5145.4 5151.6 4716.3 4773.2 4774.8 4808.3 4819.8 5310.7 5311.8 5313.6 5321.2 5327.5 4855.3 4912.5 5071.3 5077.8 5085.6 5039.9 5072.0 5079.7 5086.0 5090.3 5034.3 5038.7 5039.0 5047.6 5089.8 4949.7 5000.5 5012.5 5014.4 5021.8 4259.1 4261.1 4266.7 4307.0 4311.4 3778.5 3780.8 3790.5 3812.6 3823.3 4273.0 4349.9 4468.3 4483.3 4515.5 4972.9 4995.6 5006.6 5035.5 5036.8 4335.4 4341.9 4343.1 4348.8 4392.6 4506.8 4511.8 4531.3 4562.7 4572.4 4751.6 4783.5 4797.4 4804.5 4807.6 5063.9 5065.6 5070.8 5072.1 5115.8 4723.4 4763.5 4793.4 4814.5 4845.7 5002.1 5015.3 5047.7 5048.7 5060.2 5129.8 5140.5 5147.2 5149.4 5149.5 5001.8 5006.9 5007.0 5010.6 5052.0 4762.9 4894.5 4953.1 4960.7 4985.7 4876.8 4880.5 4893.2 4916.9 4953.4 4798.4 4980.0 4986.8 4988.1 5029.3 4918.8 4948.4 4963.3 4971.2 4985.7 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | [#1] ext.html Cycles:180 Average:53352.87 Median:34695.97 stddev:112015.29 (322.8%) stddev-sans-first:112259.42 16:23:57 INFO - PROCESS | 28515 | Values: 568.1 578.1 586.5 590.9 600.3 27067.9 39528.2 40321.0 40626.2 43818.1 41715.6 41968.6 42001.8 42093.8 42100.6 7554.2 7865.0 8008.0 8014.6 8017.2 7520.1 7742.3 7756.3 10125.3 10413.3 10465.4 10497.4 10503.3 10515.7 10530.9 10249.5 10302.7 10314.1 10314.4 10328.9 7475.1 9873.3 10268.4 10279.9 10639.1 38829.0 39055.0 39153.4 39193.6 39203.0 3001.9 3072.8 3118.0 3142.2 3192.9 29620.9 29652.1 29909.3 30042.0 30169.5 3624.9 3629.2 3632.6 3642.9 3656.3 250987.0 251739.0 252319.0 252414.0 252497.0 52050.0 52462.9 52780.2 52839.0 52853.0 33428.3 34497.0 34576.7 36099.8 55061.4 15088.2 15147.2 15181.6 15203.8 15220.0 10471.8 11578.4 11676.5 11697.2 11698.8 17967.5 18508.8 18513.4 18977.2 19031.6 6876.0 6917.8 6976.8 6982.5 6988.3 788.7 869.7 927.7 990.9 1027.0 27731.4 28426.0 28439.0 28503.6 28615.3 917.7 957.5 982.6 985.2 1001.5 15265.3 15277.0 15293.6 15452.0 15549.3 416536.0 626858.0 627344.0 630281.0 635938.0 50845.2 50892.2 51418.7 51439.2 51444.7 1083.8 1193.8 1215.1 1219.9 1223.5 25461.9 29060.4 29071.9 29549.0 29774.0 41663.2 42291.7 42335.8 42369.4 42424.6 329721.0 332247.0 332411.0 332711.0 333631.0 24843.3 26077.2 26086.0 26086.1 26087.3 41527.6 41680.6 41995.0 43033.8 43039.4 35553.3 36224.8 36548.3 36751.6 36915.4 35423.6 35518.3 35881.8 35884.0 35993.8 34815.3 35665.8 35746.5 36190.1 36317.5 30089.0 32490.5 32716.0 32897.3 33098.8 24278.9 24783.8 25137.1 25402.1 25490.4 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | [#2] jquery.html Cycles:180 Average:24948.57 Median:5248.79 stddev:66213.40 (1261.5%) stddev-sans-first:66373.38 16:23:57 INFO - PROCESS | 28515 | Values: 344.9 368.1 379.9 380.6 389.3 13181.4 16274.4 16343.2 16770.6 17588.1 17232.3 17560.4 18169.1 18487.9 18667.4 5005.4 5124.9 5246.1 5251.5 5253.7 3755.4 5149.6 5177.6 5786.3 6156.8 6198.7 6215.4 6218.7 6225.4 6252.4 5962.6 6167.2 6172.4 6173.3 6175.6 3414.4 3608.1 3609.4 5412.6 5418.8 19333.0 21150.0 21365.9 22917.9 23006.3 1905.5 2019.8 2373.4 2487.9 2547.2 13691.5 13736.1 13790.1 13893.7 14484.3 1686.5 1698.7 1710.4 1782.7 1812.2 68770.0 68904.7 69338.0 69679.3 70139.3 8847.8 9333.7 9680.6 9927.5 10337.3 12386.7 13224.5 13249.9 13667.1 13744.9 7486.4 7708.6 7714.9 7722.7 7876.4 7497.3 7521.7 7672.5 7684.9 7690.5 6236.3 6352.5 6363.5 6376.9 6422.1 1719.6 1768.5 1773.2 1811.6 1860.4 874.4 882.9 917.7 922.7 932.9 16902.7 17298.5 17835.2 18586.1 18605.9 862.4 883.5 917.1 934.4 951.5 7898.6 10408.1 10472.5 10493.4 10553.5 390071.0 401756.0 405588.0 406480.0 406928.0 67280.3 68450.0 69172.7 69432.3 70047.6 899.4 917.2 927.7 934.0 937.8 16448.2 16510.8 16957.2 17082.7 17153.0 39336.2 39633.4 40911.4 41039.2 41486.6 54374.2 56801.4 57187.7 57412.1 57902.7 7326.2 7348.0 7469.3 7506.3 7508.2 10420.0 11048.4 11551.4 11605.6 11636.8 9870.7 9882.1 9915.1 9926.3 10190.1 9806.9 9993.4 10009.7 10015.4 10049.0 9821.9 9838.2 9989.8 10035.2 10317.2 7826.9 7891.3 8019.2 8065.1 8071.4 7510.8 9617.7 9646.6 9650.7 10014.4 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | [#3] mootools.html Cycles:180 Average:5186.66 Median:5201.80 stddev:3008.77 (57.8%) stddev-sans-first:3002.69 16:23:57 INFO - PROCESS | 28515 | Values: 1253.2 1377.6 1427.6 1502.9 1737.0 6973.8 7676.4 7964.5 7977.0 8201.1 7805.7 8018.4 8026.9 8037.1 8040.6 2230.1 2277.6 2279.9 2280.0 2281.5 2195.5 2218.9 2233.5 2242.4 2246.5 2194.0 2210.8 2217.8 2218.1 2218.1 2145.0 2169.9 2171.2 2172.8 2173.1 2161.5 2163.1 2168.0 2169.8 2171.8 7759.2 7997.4 8016.7 8035.9 8109.9 4624.0 4715.7 4737.7 4740.6 4746.7 2798.6 2874.8 2908.0 2916.5 2926.3 925.9 942.2 942.8 949.3 953.5 11009.4 11132.2 11137.4 11145.2 11156.2 3899.0 4023.4 4141.1 4147.1 4169.4 8577.7 8605.1 8656.9 8696.6 8711.7 4638.4 4639.2 4647.7 4653.4 4656.5 3554.3 3792.0 3911.4 3916.6 4276.9 3263.1 3280.9 3295.1 3305.2 3316.0 983.7 992.9 993.2 997.1 1007.2 701.3 718.5 765.1 779.2 822.2 5036.6 5072.1 5189.5 5214.1 5314.4 394.0 399.3 399.9 401.6 413.3 3492.3 3554.2 3617.7 3634.3 3656.8 9496.6 9891.2 9997.3 10010.4 10031.2 9907.6 9937.4 9951.2 9972.5 9992.4 6734.4 6991.5 6994.9 7188.2 7194.7 5581.8 5717.2 5785.8 5790.0 5868.6 9315.1 9523.2 9534.3 9545.5 9572.8 9372.1 9505.2 9563.7 9669.5 9691.4 6109.5 6802.0 6945.1 6980.6 7205.2 7580.0 7614.7 7615.8 7623.8 7663.1 6879.3 6967.7 7048.3 7104.7 7116.3 6916.0 7019.4 7029.4 7069.3 7103.9 5955.5 6027.0 6030.3 6030.7 6038.1 4394.6 4587.2 4607.4 4628.6 4718.9 4585.5 4615.5 4627.9 4635.6 4636.2 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | [#4] prototype.html Cycles:180 Average:4255.35 Median:4878.48 stddev:1230.01 (25.2%) stddev-sans-first:1197.08 16:23:57 INFO - PROCESS | 28515 | Values: 299.0 304.1 304.9 309.7 311.3 4884.5 4900.5 4922.2 4932.8 4960.0 4827.5 4831.2 4860.1 4874.3 4882.6 3638.2 3714.3 3730.0 3823.2 3826.0 3795.5 3813.8 3815.2 3820.3 3830.6 3620.8 3785.9 3791.9 3792.9 3793.7 3671.9 3783.2 3786.3 3789.3 3790.4 3358.0 3361.4 3370.7 3372.4 3382.6 4748.8 4760.1 4767.5 4767.7 4769.5 4276.5 4326.7 4369.7 4372.7 4391.9 5027.9 5034.4 5054.6 5089.2 5111.1 3681.9 3682.0 3690.4 3695.4 3704.7 5132.2 5255.9 5274.3 5280.9 5297.4 3770.7 3773.6 3778.5 3778.7 3787.9 3844.6 3851.3 3851.6 3854.5 3854.9 4321.5 4356.7 4360.1 4362.3 4364.6 4855.7 5040.4 5059.4 5065.6 5065.6 1296.7 1322.0 1327.5 1331.3 1341.4 865.9 868.8 892.9 895.2 896.1 4337.5 4376.9 4520.8 4521.6 4529.5 5239.0 5242.3 5242.9 5267.6 5275.9 4297.9 4552.1 4556.5 4563.2 4598.7 2785.1 2804.2 2821.4 2826.8 2833.5 4969.5 4971.4 4977.5 5009.3 5019.5 5115.7 5371.5 5379.7 5437.8 5446.7 4985.2 4994.3 5009.6 5013.9 5032.8 5305.5 5308.4 5321.5 5354.4 5363.4 5098.3 5123.3 5186.1 5211.2 5212.4 4983.8 5017.8 5035.0 5037.9 5053.4 3732.6 3748.9 3754.7 3765.7 3773.7 5129.7 5295.5 5319.6 5320.4 5345.7 5127.9 5154.5 5154.7 5160.2 5173.7 5131.9 5169.6 5173.4 5178.5 5182.7 4819.3 4940.0 5031.5 5032.5 5055.4 5049.5 5058.3 5060.9 5074.3 5087.2 4934.9 5039.2 5055.3 5063.4 5065.0 16:23:57 INFO - PROCESS | 28515 | 16:23:57 INFO - PROCESS | 28515 | [#5] yui.html Cycles:180 Average:16848.52 Median:561.90 stddev:23562.25 (4193.3%) stddev-sans-first:23596.94 16:23:57 INFO - PROCESS | 28515 | Values: 646.5 650.7 684.0 691.5 720.5 11551.1 11911.6 12381.2 12395.0 12540.9 11786.4 11813.6 11878.2 11897.2 11910.3 629.4 767.0 768.4 784.4 785.6 797.1 806.1 806.7 806.7 807.5 841.6 841.6 844.4 844.5 845.7 662.9 777.5 815.9 838.3 839.0 602.3 602.8 602.8 603.4 603.9 11184.4 11206.6 11212.9 11221.7 11239.5 11874.6 12213.9 12286.5 12330.6 12526.3 10681.9 10726.5 10815.7 10953.2 10995.7 3890.4 3944.5 3952.7 3956.7 4039.0 58876.4 59001.7 59021.1 59041.0 59631.4 9292.5 9390.9 9535.1 9744.7 9895.6 16421.4 16426.8 16465.7 16597.5 16658.8 6718.3 6778.4 6834.0 6933.0 7022.3 3785.1 3807.3 3833.1 3846.5 3850.5 2737.6 2833.8 2843.6 2844.8 2845.7 487.0 492.7 494.0 531.4 556.0 526.3 596.9 598.8 611.3 618.1 8148.6 8287.5 8326.0 8478.2 8932.0 518.2 567.8 604.4 629.2 641.8 3689.3 3862.3 3953.1 3994.8 4015.6 74055.0 76444.6 76766.0 89528.0 91779.0 78513.2 82593.0 83852.9 84577.6 84583.2 69867.7 71687.0 72302.6 72596.6 75003.2 8046.6 8240.3 8367.5 8498.6 8604.0 41565.0 42307.0 42630.0 42719.0 44592.3 58485.0 60994.7 61580.4 62763.0 63617.0 10533.9 11001.2 11014.7 11087.3 11121.8 12347.3 12711.4 12735.1 12749.4 13515.3 10671.3 10845.2 10939.9 11051.1 11138.3 11052.8 11183.0 11535.1 11601.7 11805.9 11389.5 11923.1 12002.5 12063.2 12071.9 9891.0 10037.0 10255.7 10435.4 10449.2 9158.4 9295.4 9536.9 9543.6 9565.2 16:23:57 INFO - PROCESS | 28515 | -------- Summary: end -------- 16:23:57 INFO - PROCESS | 28515 | 16:23:58 INFO - TEST-INFO | 28515: exit 0 16:23:58 INFO - TEST-OK | dromaeo_css | took 1119377ms 16:23:58 INFO - TEST-START | kraken 16:23:58 INFO - operating with platform_type : linux_ 16:23:58 INFO - Initialising browser for kraken test... 16:23:58 INFO - TEST-INFO | started process 28715 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphmP8Jf/profile http://localhost:48746/getInfo.html) 16:24:03 INFO - PROCESS | 28715 | __metrics Screen width/height:1600/1200 16:24:03 INFO - PROCESS | 28715 | colorDepth:24 16:24:03 INFO - PROCESS | 28715 | Browser inner width/height: 1024/697 16:24:03 INFO - PROCESS | 28715 | __metrics 16:24:04 INFO - PROCESS | 28715 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:24:05 INFO - TEST-INFO | 28715: exit 0 16:24:05 INFO - Browser initialized. 16:24:05 INFO - Running cycle 1/1 for kraken test... 16:24:05 INFO - TEST-INFO | started process 28796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphmP8Jf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:17 INFO - PROCESS | 28796 | RSS: Main: 158912512 16:24:17 INFO - PROCESS | 28796 | 16:24:20 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48746/tests/kraken/driver.html?testName=audio-beat-detection) 16:24:21 INFO - PROCESS | 28796 | RSS: Main: 216461312 16:24:21 INFO - PROCESS | 28796 | 16:24:23 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48746/tests/kraken/driver.html?testName=audio-dft) 16:24:24 INFO - PROCESS | 28796 | RSS: Main: 204234752 16:24:24 INFO - PROCESS | 28796 | 16:24:27 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48746/tests/kraken/driver.html?testName=audio-fft) 16:24:28 INFO - PROCESS | 28796 | RSS: Main: 271335424 16:24:28 INFO - PROCESS | 28796 | 16:24:30 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48746/tests/kraken/driver.html?testName=audio-oscillator) 16:24:30 INFO - PROCESS | 28796 | RSS: Main: 182001664 16:24:30 INFO - PROCESS | 28796 | 16:24:33 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48746/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:24:33 INFO - PROCESS | 28796 | RSS: Main: 185769984 16:24:33 INFO - PROCESS | 28796 | 16:24:36 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48746/tests/kraken/driver.html?testName=imaging-darkroom) 16:24:37 INFO - PROCESS | 28796 | RSS: Main: 222810112 16:24:37 INFO - PROCESS | 28796 | 16:24:41 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48746/tests/kraken/driver.html?testName=imaging-desaturate) 16:24:41 INFO - PROCESS | 28796 | RSS: Main: 217440256 16:24:41 INFO - PROCESS | 28796 | 16:24:44 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48746/tests/kraken/driver.html?testName=json-parse-financial) 16:24:45 INFO - PROCESS | 28796 | RSS: Main: 220819456 16:24:45 INFO - PROCESS | 28796 | 16:24:46 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48746/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:24:47 INFO - PROCESS | 28796 | RSS: Main: 207069184 16:24:47 INFO - PROCESS | 28796 | 16:24:49 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:24:50 INFO - PROCESS | 28796 | RSS: Main: 211550208 16:24:50 INFO - PROCESS | 28796 | 16:24:52 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:24:52 INFO - PROCESS | 28796 | RSS: Main: 210014208 16:24:52 INFO - PROCESS | 28796 | 16:24:55 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:24:56 INFO - PROCESS | 28796 | RSS: Main: 224772096 16:24:56 INFO - PROCESS | 28796 | 16:24:59 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:24:59 INFO - PROCESS | 28796 | RSS: Main: 224735232 16:24:59 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | Cycle 1(1): loaded http://localhost:48746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48746/tests/kraken/driver.html?testName=ai-astar) 16:25:01 INFO - PROCESS | 28796 | RSS: Main: 267096064 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | __start_tp_report 16:25:01 INFO - PROCESS | 28796 | _x_x_mozilla_page_load 16:25:01 INFO - PROCESS | 28796 | _x_x_mozilla_page_load_details 16:25:01 INFO - PROCESS | 28796 | |i|pagename|runs| 16:25:01 INFO - PROCESS | 28796 | |0;ai-astar;104;115;114;116;114;113;113;112;114;114 16:25:01 INFO - PROCESS | 28796 | |1;audio-beat-detection;148;123;125;180;122;127;154;125;155;123 16:25:01 INFO - PROCESS | 28796 | |2;audio-dft;161;160;162;161;161;162;162;162;161;160 16:25:01 INFO - PROCESS | 28796 | |3;audio-fft;75;74;77;122;77;78;76;76;100;75 16:25:01 INFO - PROCESS | 28796 | |4;audio-oscillator;85;85;111;85;85;86;126;86;86;133 16:25:01 INFO - PROCESS | 28796 | |5;imaging-gaussian-blur;118;118;117;117;116;119;118;118;118;118 16:25:01 INFO - PROCESS | 28796 | |6;imaging-darkroom;147;147;145;146;146;146;146;146;146;146 16:25:01 INFO - PROCESS | 28796 | |7;imaging-desaturate;95;96;95;94;95;95;95;95;94;95 16:25:01 INFO - PROCESS | 28796 | |8;json-parse-financial;64;63;63;65;63;65;65;65;63;71 16:25:01 INFO - PROCESS | 28796 | |9;json-stringify-tinderbox;63;64;64;64;64;64;65;64;64;65 16:25:01 INFO - PROCESS | 28796 | |10;stanford-crypto-aes;79;79;78;78;78;78;81;78;78;77 16:25:01 INFO - PROCESS | 28796 | |11;stanford-crypto-ccm;132;139;128;129;134;143;131;139;179;137 16:25:01 INFO - PROCESS | 28796 | |12;stanford-crypto-pbkdf2;170;174;165;168;164;164;168;168;163;163 16:25:01 INFO - PROCESS | 28796 | |13;stanford-crypto-sha256-iterative;67;66;70;66;66;66;66;67;70;65 16:25:01 INFO - PROCESS | 28796 | __end_tp_report 16:25:01 INFO - PROCESS | 28796 | __start_cc_report 16:25:01 INFO - PROCESS | 28796 | _x_x_mozilla_cycle_collect,1952 16:25:01 INFO - PROCESS | 28796 | __end_cc_report 16:25:01 INFO - PROCESS | 28796 | __startTimestamp1467242701706__endTimestamp 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | ------- Summary: start ------- 16:25:01 INFO - PROCESS | 28796 | Number of tests: 14 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.31 (2.9%) stddev-sans-first:1.17 16:25:01 INFO - PROCESS | 28796 | Values: 104.0 115.0 114.0 116.0 114.0 113.0 113.0 112.0 114.0 114.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:137.50 stddev:19.93 (14.5%) stddev-sans-first:20.82 16:25:01 INFO - PROCESS | 28796 | Values: 148.0 123.0 125.0 180.0 122.0 127.0 154.0 125.0 155.0 123.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 16:25:01 INFO - PROCESS | 28796 | Values: 161.0 160.0 162.0 161.0 161.0 162.0 162.0 162.0 161.0 160.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#3] audio-fft Cycles:10 Average:83.00 Median:76.00 stddev:15.68 (20.6%) stddev-sans-first:16.37 16:25:01 INFO - PROCESS | 28796 | Values: 75.0 74.0 77.0 122.0 77.0 78.0 76.0 76.0 100.0 75.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:85.00 stddev:19.07 (22.4%) stddev-sans-first:19.74 16:25:01 INFO - PROCESS | 28796 | Values: 85.0 85.0 111.0 85.0 85.0 86.0 126.0 86.0 86.0 133.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 16:25:01 INFO - PROCESS | 28796 | Values: 118.0 118.0 117.0 117.0 116.0 119.0 118.0 118.0 118.0 118.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 16:25:01 INFO - PROCESS | 28796 | Values: 147.0 147.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:25:01 INFO - PROCESS | 28796 | Values: 95.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:2.41 (3.7%) stddev-sans-first:2.54 16:25:01 INFO - PROCESS | 28796 | Values: 64.0 63.0 63.0 65.0 63.0 65.0 65.0 65.0 63.0 71.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 16:25:01 INFO - PROCESS | 28796 | Values: 63.0 64.0 64.0 64.0 64.0 64.0 65.0 64.0 64.0 65.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:1.07 (1.4%) stddev-sans-first:1.12 16:25:01 INFO - PROCESS | 28796 | Values: 79.0 79.0 78.0 78.0 78.0 78.0 81.0 78.0 78.0 77.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:138.00 stddev:14.83 (10.7%) stddev-sans-first:15.50 16:25:01 INFO - PROCESS | 28796 | Values: 132.0 139.0 128.0 129.0 134.0 143.0 131.0 139.0 179.0 137.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:168.00 stddev:3.56 (2.1%) stddev-sans-first:3.57 16:25:01 INFO - PROCESS | 28796 | Values: 170.0 174.0 165.0 168.0 164.0 164.0 168.0 168.0 163.0 163.0 16:25:01 INFO - PROCESS | 28796 | 16:25:01 INFO - PROCESS | 28796 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.50 stddev:1.73 (2.6%) stddev-sans-first:1.83 16:25:01 INFO - PROCESS | 28796 | Values: 67.0 66.0 70.0 66.0 66.0 66.0 66.0 67.0 70.0 65.0 16:25:01 INFO - PROCESS | 28796 | -------- Summary: end -------- 16:25:01 INFO - PROCESS | 28796 | 16:25:02 INFO - TEST-INFO | 28796: exit 0 16:25:02 INFO - TEST-OK | kraken | took 64171ms 16:25:02 INFO - SUITE-END | took 1183s 16:25:02 INFO - Completed test suite (00:19:43) 16:25:02 INFO - javascript benchmark 16:25:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2595.5717306644397, 2633.1705512763465, 2660.1886424640484, 2665.3869609989715, 2673.3049407154135, 4658.860234192971, 4702.529747025295, 4714.320532014904, 4799.544043315884, 4858.174110401232, 4752.097101550703, 4763.8809029774275, 4771.8807029824275, 4772.284157376395, 4800.615950723943, 4342.761148136851, 4364.563543645641, 4394.0, 4422.845200417986, 4449.132419178261, 27.18045759504559, 4420.248557745175, 4446.333050042487, 4460.710053846506, 4484.686071974963, 4347.021920067991, 4403.119376124756, 4409.735415875058, 4410.492793328776, 4437.423134992447, 4398.296272596369, 4398.714083584557, 4406.272964960777, 4407.867763967086, 4446.244138496463, 4254.170436764832, 4345.348197770328, 4347.021920067991, 4360.236958532276, 4404.801783919731, 4678.251479763221, 4679.391679081732, 4725.763711814396, 4742.430908290993, 4799.0, 5101.234814777776, 5109.718965456898, 5112.616553758446, 5114.744262786846, 5123.538881500645, 4914.11545921734, 5084.949150508484, 5108.055009823194, 5145.382554093495, 5151.5878729701535, 4716.292556116576, 4773.236282194867, 4774.809007639725, 4808.2547205183255, 4819.783109759999, 5310.707911064851, 5311.787528498817, 5313.628046036741, 5321.201819727072, 5327.547158491494, 4855.344528488608, 4912.484189160158, 5071.264666623319, 5077.822276220315, 5085.593152547787, 5039.874003149951, 5072.0, 5079.720615366116, 5085.974570127126, 5090.312807770978, 5034.270030845507, 5038.6976781392505, 5038.974805125951, 5047.621428392812, 5089.847304580869, 4949.7030178189425, 5000.549950504472, 5012.473690262543, 5014.373203349582, 5021.824236151717, 4259.084296876186, 4261.10516791471, 4266.743995360288, 4307.0, 4311.374850646639, 3778.4521244419957, 3780.8298626561873, 3790.450384694204, 3812.599677033927, 3823.292690852146, 4273.0, 4349.9130017399475, 4468.32975053738, 4483.260262056724, 4515.525869783691, 4972.8508144755715, 4995.600351971833, 5006.574441172468, 5035.496450355008, 5036.84889453317, 4335.3713711511655, 4341.891452713708, 4343.066240758252, 4348.816254591932, 4392.582704643054, 4506.81972721094, 4511.796969136397, 4531.274996000624, 4562.726236425825, 4572.428446444194, 4751.596114330251, 4783.521647835189, 4797.4482934462285, 4804.5195480451675, 4807.639427043056, 5063.949360506348, 5065.594752419871, 5070.796811168894, 5072.1123803335, 5115.820946266863, 4723.433188017322, 4763.476017638126, 4793.400824896888, 4814.470408255019, 4845.709257444564, 5002.099622067917, 5015.297858299914, 5047.722375269395, 5048.7223202722735, 5060.240963855451, 5129.820456284012, 5140.4859514049795, 5147.202183661539, 5149.407818100966, 5149.510796474329, 5001.824936127218, 5006.899862002667, 5007.048660754445, 5010.574101201439, 5052.040112378636, 4762.880927976828, 4894.510548945077, 4953.0589188054155, 4960.677555958851, 4985.70085794839, 4876.829310974099, 4880.487548807466, 4893.21708526634, 4916.852494425173, 4953.430355509163, 4798.4001999750035, 4979.975100124476, 4986.825461108844, 4988.12707776145, 5029.346184995927, 4918.803247870045, 4948.38145231846, 4963.280324353021, 4971.229459433794, 4985.700857948535], "value": 4642.4638808379095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.0712035821432, 578.1183694865327, 586.4780345492511, 590.8581940334321, 600.3036477685886, 27067.864660676692, 39528.209435811266, 40321.0, 40626.18747625053, 43818.12363752731, 41715.58284417155, 41968.58031419685, 42001.78999105008, 42093.7895310523, 42100.578994210045, 7554.169041405441, 7864.960675196616, 8007.95996020022, 8014.639341229629, 8017.198280171965, 7520.135184453776, 7742.341900938427, 7756.301932826044, 10125.341852779582, 10413.323133996304, 10465.372077675325, 10497.4226417547, 10503.264771466, 10515.73710657232, 10530.894691053069, 10249.487525623726, 10302.690919272434, 10314.071733543942, 10314.432706201193, 10328.896711032943, 7475.102987641463, 9873.259505537042, 10268.35706286998, 10279.897201027969, 10639.095676867399, 38829.0, 39055.02362440933, 39153.41269880954, 39193.60806391928, 39203.0, 3001.864916078782, 3072.800267982574, 3117.984410077958, 3142.213444227178, 3192.8882489113003, 29620.851895740383, 29652.110436686933, 29909.25226869324, 30042.0, 30169.547456788165, 3624.9456258156147, 3629.201575653352, 3632.6185750496347, 3642.908927276813, 3656.305301992613, 250986.99999999817, 251739.0, 252319.0, 252414.0, 252497.0, 52050.0, 52462.85222886639, 52780.20829687558, 52839.0, 52853.0, 33428.3314333712, 34497.0, 34576.677915260596, 36099.819500902326, 55061.449385506436, 15088.24558772079, 15147.242637868063, 15181.62045948838, 15203.771943420857, 15220.0, 10471.84292235617, 11578.42107894602, 11676.532938682527, 11697.23967942081, 11698.766024679458, 17967.460976170474, 18508.81491185098, 18513.44459666212, 18977.24091036343, 19031.619367612846, 6876.0, 6917.827054323682, 6976.825579360455, 6982.546134501241, 6988.336108069725, 788.712120076168, 869.7086476030601, 927.679950417105, 990.8860481044773, 1026.958921643126, 27731.44537109287, 28426.0, 28439.0, 28503.572446412905, 28615.28461788472, 917.655879045358, 957.5116690487762, 982.5873133284038, 985.2019863910256, 1001.4942454060798, 15265.313060912285, 15277.0, 15293.6176595586, 15452.0, 15549.30028148736, 416536.0, 626858.0, 627344.0, 630281.0, 635938.0, 50845.23732144021, 50892.236616450784, 51418.74290628598, 51439.22841157386, 51444.74277628588, 1083.7507373304027, 1193.773183095209, 1215.0583297943988, 1219.8594314316142, 1223.5105957616847, 25461.87269063717, 29060.418791623626, 29071.854640727506, 29549.0, 29774.0, 41663.16673666449, 42291.71353580803, 42335.7883210582, 42369.36445953252, 42424.57575424206, 329721.0, 332247.0, 332411.0, 332711.0, 333631.0, 24843.254702358958, 26077.217683469527, 26086.0, 26086.086986956125, 26087.347816304744, 41527.58472415237, 41680.58319416767, 41995.0, 43033.784831075645, 43039.354409683256, 35553.28893422169, 36224.818875905454, 36548.26903461969, 36751.632483674824, 36915.44626830546, 35423.64576354204, 35518.289634207686, 35881.82059089688, 35884.026378548384, 35993.820030899675, 34815.259986987076, 35665.82167089252, 35746.463803042454, 36190.09524761902, 36317.45523817198, 30089.0, 32490.512642310856, 32716.0, 32897.342053158325, 33098.83450582732, 24278.94705282736, 24783.752162478144, 25137.120200792884, 25402.110926117497, 25490.362740931625], "value": 18211.055384069474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.8723972130312, 368.0779646984305, 379.8594520027592, 380.61938061938054, 389.2953753705793, 13181.406836692347, 16274.43039493618, 16343.182840857955, 16770.58073548162, 17588.12059397028, 17232.310707571683, 17560.385386511476, 18169.09154542271, 18487.90756046218, 18667.439976800717, 5005.370032719728, 5124.8975020499565, 5246.055709972204, 5251.527362537371, 5253.658512196695, 3755.380362240227, 5149.639525233235, 5177.58579313654, 5786.305643322808, 6156.815295541141, 6198.721057552421, 6215.440610345068, 6218.657973811439, 6225.408586184305, 6252.374762523734, 5962.552808539355, 6167.198264225667, 6172.382761723837, 6173.32093469718, 6175.59858609189, 3414.3854106260865, 3608.0799396154075, 3609.3864043112735, 5412.5940554458575, 5418.756155972991, 19333.0, 21150.0, 21365.893170534204, 22917.88541057284, 23006.309810705705, 1905.4664693885734, 2019.8081182287806, 2373.3947843299848, 2487.8880450379775, 2547.210364786922, 13691.452341906312, 13736.10715303502, 13790.103643263155, 13893.722125557431, 14484.275786210648, 1686.5109118355786, 1698.6687595918802, 1710.3586155191804, 1782.7236778299384, 1812.2207450796152, 68770.0, 68904.6554767228, 69338.0, 69679.30320696779, 70139.29860701378, 8847.778805529913, 9333.673321433716, 9680.612775489042, 9927.453990030474, 10337.328073675337, 12386.690332741753, 13224.47102115924, 13249.93375033138, 13667.111637743516, 13744.862551374557, 7486.438517111238, 7708.5760283183845, 7714.9228507715325, 7722.652480638385, 7876.369890408753, 7497.2877576630135, 7521.699132034767, 7672.5012874163, 7684.923150768533, 7690.461667683208, 6236.345183755731, 6352.523560732963, 6363.459105975951, 6376.904346434807, 6422.068800023971, 1719.6388758360633, 1768.51365874383, 1773.202059073422, 1811.610503741702, 1860.4139695995718, 874.4447275979799, 882.8808110904946, 917.7109210598641, 922.7093465558328, 932.9440233586007, 16902.74645880313, 17298.481045568653, 17835.197416116305, 18586.07069646512, 18605.90697046506, 862.3532350736947, 883.4875772052194, 917.1378903830378, 934.4066517761134, 951.4909523404945, 7898.60506974649, 10408.115310198567, 10472.476376181314, 10493.370397776005, 10553.525091370906, 390071.0, 401756.0, 405588.0, 406480.0, 406928.0, 67280.32719672838, 68450.0, 69172.65413672899, 69432.3056769436, 70047.64976175189, 899.4243684042218, 917.206616276929, 927.6521304510809, 934.0379409208526, 937.8124375125002, 16448.17759112016, 16510.75233871469, 16957.236924338682, 17082.658346833243, 17153.0, 39336.213275734895, 39633.40549891812, 40911.38632920449, 41039.17921641491, 41486.58513414827, 54374.184387235015, 56801.431985679614, 57187.714061429426, 57412.1388179186, 57902.7104864473, 7326.194118647051, 7347.963260183665, 7469.327760501581, 7506.3244308012545, 7508.249175082448, 10420.0, 11048.392338421463, 11551.364674942959, 11605.593804216813, 11636.767264654829, 9870.703878883645, 9882.061204185591, 9915.058069483388, 9926.255530835071, 10190.133838623504, 9806.852897206405, 9993.350432221881, 10009.749756256151, 10015.399076055168, 10049.0, 9821.870484894043, 9838.163756080814, 9989.800203996025, 10035.24735644844, 10317.226208034577, 7826.882596760939, 7891.289783919475, 8019.157988411366, 8065.072516660424, 8071.43499955021, 7510.8497830042, 9617.71146865595, 9646.565904534453, 9650.71047868565, 10014.383315354446], "value": 8507.01101347015, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1253.1603825436957, 1377.6418131285868, 1427.5717284814557, 1502.8872834537412, 1736.9826301736978, 6973.790786276407, 7676.38588912887, 7964.482308649948, 7977.0, 8201.05687845898, 7805.687772489094, 8018.398620103487, 8026.879596806038, 8037.115917249098, 8040.597970101486, 2230.0856648774006, 2277.635578307471, 2279.851809632377, 2280.0195915756217, 2281.5208806150727, 2195.538936823269, 2218.866867987918, 2233.475133343663, 2242.439390152462, 2246.539459410817, 2194.0346247651064, 2210.8120809731113, 2217.84475086744, 2218.0794970087395, 2218.1238410827714, 2144.9892750536305, 2169.858959167657, 2171.185805323004, 2172.7935846094597, 2173.1307477009163, 2161.492049368397, 2163.0698799516204, 2167.967480487794, 2169.761326254111, 2171.815395691368, 7759.185285544993, 7997.400194985399, 8016.67933282668, 8035.919640803576, 8109.878351824727, 4623.976880115578, 4715.693479923794, 4737.692050016738, 4740.620750339965, 4746.738929358884, 2798.5802129680505, 2874.841883696396, 2907.985460072707, 2916.47503449379, 2926.312316605596, 925.8935222449437, 942.2085448223456, 942.7972985808016, 949.2690628216235, 953.4660590069514, 11009.394483303415, 11132.220744547885, 11137.387443690593, 11145.21983461158, 11156.219064665476, 3899.0, 4023.350426325251, 4141.088960428701, 4147.066909945238, 4169.4162817205615, 8577.699780507652, 8605.053444121142, 8656.913430865736, 8696.608652610648, 8711.738647840575, 4638.420197475316, 4639.164950308977, 4647.674662773641, 4653.371794807657, 4656.511066338054, 3554.289142171576, 3791.962080379216, 3911.413288006765, 3916.6083391661177, 4276.850310239127, 3263.0537144228383, 3280.9343813124083, 3295.126791400281, 3305.2397948471985, 3316.0, 983.7393090830935, 992.925530585209, 993.1508560181086, 997.1225321716843, 1007.2395341517108, 701.2881924846286, 718.4683334332557, 765.0589774577301, 779.2052106851094, 822.2188920525525, 5036.597072234213, 5072.087024335655, 5189.481051894856, 5214.113600687892, 5314.415414304423, 393.95272567291534, 399.3052089364485, 399.8780371986514, 401.6204686571162, 413.2912055824276, 3492.2840817632323, 3554.2180720241504, 3617.6744093031753, 3634.2913131939545, 3656.8171591420323, 9496.572654230577, 9891.208703303862, 9997.250206234561, 10010.399376037461, 10031.247656425798, 9907.603695852085, 9937.354071985297, 9951.203903687687, 9972.501374931224, 9992.400455972665, 6734.427573656196, 6991.510594258353, 6994.860102797916, 7188.17336006366, 7194.676239569232, 5581.832545023656, 5717.199592057199, 5785.810585595764, 5789.9710501447225, 5868.647881127146, 9315.115064068903, 9523.19052880513, 9534.332596718163, 9545.474998875128, 9572.76068098303, 9372.109649583279, 9505.19205867509, 9563.665271715456, 9669.516524173623, 9691.370060946016, 6109.480694141048, 6801.965990170018, 6945.0624165739, 6980.616066116412, 7205.207427182901, 7580.0, 7614.657340419583, 7615.847683046419, 7623.771286861403, 7663.080430348394, 6879.312068793281, 6967.686454109678, 7048.295170483116, 7104.680289387094, 7116.288371163049, 6915.965420172867, 7019.3682568569075, 7029.437644988491, 7069.328413800681, 7103.893441598277, 5955.523558115254, 6027.0, 6030.306514750684, 6030.7286172121485, 6038.12447195163, 4394.560543945579, 4587.243104887656, 4607.401037865056, 4628.583427491407, 4718.929216061693, 4585.5414458555215, 4615.469221039623, 4627.8611641650805, 4635.629149668086, 4636.235021221461], "value": 4052.01032560835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.99551506727397, 304.092284530676, 304.8750012494877, 309.6516419028593, 311.3197663106112, 4884.485809399089, 4900.5099490051025, 4922.1632322505175, 4932.753362331887, 4960.0, 4827.468978412372, 4831.227003679412, 4860.100881336955, 4874.341963834881, 4882.6093912487, 3638.181409182926, 3714.312852122366, 3729.9813500932455, 3823.1971286029966, 3826.0, 3795.525559305087, 3813.809309534526, 3815.2369526094753, 3820.2550502651993, 3830.636089571486, 3620.8008559529217, 3785.9432108518395, 3791.867284645038, 3792.8672496462627, 3793.7344385892998, 3671.889843304705, 3783.224438990028, 3786.318462676717, 3789.280036793028, 3790.3935370340887, 3357.992602219349, 3361.4117529432497, 3370.747193960463, 3372.4266874631126, 3382.5624109753257, 4748.833790817304, 4760.0721707708435, 4767.451743049524, 4767.737774422405, 4769.451513075971, 4276.465441819772, 4326.697131200817, 4369.672274579388, 4372.737635741865, 4391.890202744925, 5027.924581131249, 5034.421041580228, 5054.62090343222, 5089.185730283174, 5111.08000559899, 3681.9263614727824, 3681.9815900920325, 3690.3910854708943, 3695.427208782616, 3704.666580007812, 5132.178851383798, 5255.9488102380055, 5274.340707411574, 5280.9207861882105, 5297.390800058004, 3770.6606405423645, 3773.566039905446, 3778.5465744110884, 3778.6599206071587, 3787.9242415151543, 3844.615538446133, 3851.30676478231, 3851.5955824638568, 3854.5374555053572, 3854.8990800014058, 4321.503027151918, 4356.6732495063, 4360.062586543845, 4362.30203167498, 4364.607185353333, 4855.6601037927, 5040.445550989388, 5059.418166910852, 5065.6200784941275, 5065.645404821627, 1296.708240645866, 1321.9886786608251, 1327.4955516903708, 1331.2811082015603, 1341.4030756313348, 865.9393842431095, 868.769776009358, 892.8571428571528, 895.2122132523468, 896.0815164456484, 4337.479502459725, 4376.912461750747, 4520.773961301988, 4521.62090562375, 4529.5243999380245, 5238.973805131026, 5242.318498595235, 5242.947570524322, 5267.578593712494, 5275.947240527622, 4297.935530966975, 4552.066826300666, 4556.476005259371, 4563.155816173952, 4598.655100867449, 2785.1366076516347, 2804.1587523743206, 2821.4498172856506, 2826.770354895627, 2833.504136776056, 4969.503049695001, 4971.353724015927, 4977.452480227172, 5009.348784657971, 5019.548240658285, 5115.672369072064, 5371.516563509303, 5379.67721936685, 5437.755301011385, 5446.673199608036, 4985.177445721488, 4994.300797888372, 5009.574186194214, 5013.924791128136, 5032.823851165192, 5305.495977882095, 5308.416074231909, 5321.494458026481, 5354.3842458117815, 5363.356397232357, 5098.260752190986, 5123.280790651685, 5186.118359878905, 5211.166213405837, 5212.374515058217, 4983.800647974185, 5017.795729025081, 5034.974825125851, 5037.949620503748, 5053.36832895888, 3732.5520937487677, 3748.8500459981296, 3754.737168398186, 3765.6987441004107, 3773.716971227114, 5129.717865517432, 5295.4969277918535, 5319.601029922694, 5320.414754377093, 5345.705986170798, 5127.948720512747, 5154.525827602697, 5154.716490592903, 5160.200168973815, 5173.689578625294, 5131.923021154611, 5169.560587350117, 5173.430922598586, 5178.456262092426, 5182.740862956762, 4819.349387832598, 4939.975300123477, 5031.471695472069, 5032.496750324938, 5055.407546622802, 5049.469805670352, 5058.317127187783, 5060.924086138637, 5074.264231686311, 5087.160618498055, 4934.925976110433, 5039.244113383022, 5055.34280543527, 5063.392392912874, 5064.974675126601], "value": 3866.326677911914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.476354153136, 650.6649075726002, 683.9521233513656, 691.5263044814302, 720.5388551327148, 11551.133664975128, 11911.642650720492, 12381.195222310544, 12395.008399328055, 12540.93729531351, 11786.410679466015, 11813.645590632295, 11878.227915185507, 11897.226680265798, 11910.285382877033, 629.351767679291, 766.9846603067949, 768.4390395011645, 784.3764207455058, 785.6464590934074, 797.1271457753751, 806.1132753970614, 806.705552473346, 806.7297455352464, 807.4549678966704, 841.5750046226647, 841.6338892581716, 844.3625063077368, 844.5059640110543, 845.7378212754035, 662.9303923088103, 777.5101685937823, 815.8735396013627, 838.2916435611899, 838.995805020974, 602.3043384890415, 602.8312072619674, 602.8312072619674, 603.4357875386484, 603.9335673075958, 11184.38485883276, 11206.607768728056, 11212.887871121266, 11221.719457013558, 11239.494222759995, 11874.584389546411, 12213.938930305381, 12286.508539658404, 12330.568430104993, 12526.311052892088, 10681.946590267076, 10726.517306721138, 10815.729606759895, 10953.233273670847, 10995.670129896116, 3890.396988466792, 3944.4872166618156, 3952.743071700359, 3956.742811717229, 4039.0104424415913, 58876.41123588795, 59001.7049914752, 59021.11468327978, 59041.0, 59631.40368596302, 9292.488913109708, 9390.85913711295, 9535.094166054214, 9744.692046881783, 9895.554700038516, 16421.42525011619, 16426.75359869603, 16465.6706865862, 16597.50207493753, 16658.833411665728, 6718.32816718334, 6778.423833974168, 6833.974903764376, 6932.994715766185, 7022.297770222936, 3785.1294202333615, 3807.314683357022, 3833.1375440526217, 3846.4986105992893, 3850.4609354690365, 2737.575675770259, 2833.773298136144, 2843.6018957345987, 2844.8008639395457, 2845.743883050494, 486.95130486951024, 492.7388484103431, 494.0489557601674, 531.388902761827, 556.0380541662946, 526.3052770343187, 596.9462748352669, 598.7664810723821, 611.2756383685347, 618.0636335951037, 8148.592570371457, 8287.461315014505, 8325.959255093114, 8478.236958673626, 8931.955340223258, 518.2304278146948, 567.758702551414, 604.3679774581634, 629.2480485819402, 641.8363317354044, 3689.2805902849223, 3862.343401621731, 3953.0710283083454, 3994.820233089518, 4015.558288588253, 74055.0, 76444.61777691076, 76766.0, 89528.0, 91779.0, 78513.21486785059, 82593.0, 83852.90367740733, 84577.57711211528, 84583.15416845876, 69867.65066174738, 71687.0, 72302.63848680828, 72596.63701681458, 75003.24996750071, 8046.597670116354, 8240.340772738047, 8367.496484661357, 8498.617562209593, 8603.956980215058, 41565.0, 42307.0, 42630.0, 42719.0, 44592.33111503265, 58485.0, 60994.69502652636, 61580.384196159255, 62763.0, 63617.0, 10533.894661053291, 11001.22991390595, 11014.724631884266, 11087.33475991443, 11121.83317250258, 12347.259164450163, 12711.427985740478, 12735.108542401942, 12749.362531873554, 13515.324233788468, 10671.253012289375, 10845.240833141605, 10939.945300273448, 11051.060659843683, 11138.33170009802, 11052.795908354488, 11183.0, 11535.134864885335, 11601.709957251136, 11805.881941180478, 11389.487473063566, 11923.105767067678, 12002.459889304828, 12063.21589096706, 12071.879281207075, 9890.950545247515, 10037.0, 10255.743606409602, 10435.426051567236, 10449.21630877672, 9158.40470369424, 9295.395799273025, 9536.904630953602, 9543.570539325608, 9565.186959108554], "value": 6427.673914629463, "unit": "score"}], "name": "dromaeo_css", "value": 6456.382424847725, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 116.0, 114.0, 113.0, 113.0, 112.0, 114.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 125.0, 180.0, 122.0, 127.0, 154.0, 125.0, 155.0, 123.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 161.0, 162.0, 162.0, 162.0, 161.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 77.0, 122.0, 77.0, 78.0, 76.0, 76.0, 100.0, 75.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 111.0, 85.0, 85.0, 86.0, 126.0, 86.0, 86.0, 133.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 116.0, 119.0, 118.0, 118.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 65.0, 63.0, 65.0, 65.0, 65.0, 63.0, 71.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 78.0, 78.0, 78.0, 81.0, 78.0, 78.0, 77.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 139.0, 128.0, 129.0, 134.0, 143.0, 131.0, 139.0, 179.0, 137.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 165.0, 168.0, 164.0, 164.0, 168.0, 168.0, 163.0, 163.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 66.0, 66.0, 66.0, 66.0, 67.0, 70.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1530.7, "alertThreshold": 2.0}]} 16:25:02 INFO - Return code: 0 16:25:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:25:02 INFO - # TBPL SUCCESS # 16:25:02 INFO - Running post-action listener: _resource_record_post_action 16:25:02 INFO - ##### 16:25:02 INFO - ##### Finished run-tests step (success) 16:25:02 INFO - ##### 16:25:02 INFO - Running post-run listener: _resource_record_post_run 16:25:03 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 533970944; Read time: 12; Write time: 366036 16:25:03 INFO - TinderboxPrint: CPU usage
13.0% 16:25:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:25:03 INFO - TinderboxPrint: I/O write bytes / time
533,970,944 / 366,036 16:25:03 INFO - TinderboxPrint: CPU idle
8,334.2 (87.0%) 16:25:03 INFO - TinderboxPrint: CPU user
1,200.7 (12.5%) 16:25:03 INFO - TinderboxPrint: Swap in / out
0 / 0 16:25:03 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246218752; Read time: 0; Write time: 195024 16:25:03 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 287752192; Read time: 0; Write time: 171012 16:25:03 INFO - Running post-run listener: _upload_blobber_files 16:25:03 INFO - Blob upload gear active. 16:25:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:25:03 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 16:25:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:25:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:25:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:25:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:25:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:04 INFO - (blobuploader) - INFO - Done attempting. 16:25:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:25:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:25:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:06 INFO - (blobuploader) - INFO - Done attempting. 16:25:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:25:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:09 INFO - (blobuploader) - INFO - Done attempting. 16:25:09 INFO - (blobuploader) - INFO - Iteration through files over. 16:25:09 INFO - Return code: 0 16:25:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:25:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:25:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8219a461d71138a4e833301a164813cadbcc491dd93830f9402106a86a7bf2436e6d4b05008efe050d836bd9cf540190aa09cc13170d0e6b7542a5d2e56b3262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/839f2fc128fe1e5b0503e006744bf8c69cd7f5f490a5580350b3bfc01f137f934fea4d940e9800db25ee5363c3695c9d1917e209b092c03e05bc53c1c57aff54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/112cf3342be70ae146fdba44484657d61b43589dbc8d5c3676bd6b453ae4e3dbc4c4ebf97f6cca7c1f359275ed33f8b5f3e1f72e192e3dffedd35dea93b11e75"} 16:25:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:25:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:25:09 INFO - Contents: 16:25:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8219a461d71138a4e833301a164813cadbcc491dd93830f9402106a86a7bf2436e6d4b05008efe050d836bd9cf540190aa09cc13170d0e6b7542a5d2e56b3262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/839f2fc128fe1e5b0503e006744bf8c69cd7f5f490a5580350b3bfc01f137f934fea4d940e9800db25ee5363c3695c9d1917e209b092c03e05bc53c1c57aff54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/112cf3342be70ae146fdba44484657d61b43589dbc8d5c3676bd6b453ae4e3dbc4c4ebf97f6cca7c1f359275ed33f8b5f3e1f72e192e3dffedd35dea93b11e75"} 16:25:09 INFO - Running post-run listener: copy_logs_to_upload_dir 16:25:09 INFO - Copying logs to upload dir... 16:25:09 INFO - mkdir: /builds/slave/test/build/upload/logs 16:25:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.533455 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-06-29 16:25:09.469327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 16:25:09.473261) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8219a461d71138a4e833301a164813cadbcc491dd93830f9402106a86a7bf2436e6d4b05008efe050d836bd9cf540190aa09cc13170d0e6b7542a5d2e56b3262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/839f2fc128fe1e5b0503e006744bf8c69cd7f5f490a5580350b3bfc01f137f934fea4d940e9800db25ee5363c3695c9d1917e209b092c03e05bc53c1c57aff54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/112cf3342be70ae146fdba44484657d61b43589dbc8d5c3676bd6b453ae4e3dbc4c4ebf97f6cca7c1f359275ed33f8b5f3e1f72e192e3dffedd35dea93b11e75"} build_url:https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/T-CKAbNES_eFRsGgoNfWCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8219a461d71138a4e833301a164813cadbcc491dd93830f9402106a86a7bf2436e6d4b05008efe050d836bd9cf540190aa09cc13170d0e6b7542a5d2e56b3262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/839f2fc128fe1e5b0503e006744bf8c69cd7f5f490a5580350b3bfc01f137f934fea4d940e9800db25ee5363c3695c9d1917e209b092c03e05bc53c1c57aff54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/112cf3342be70ae146fdba44484657d61b43589dbc8d5c3676bd6b453ae4e3dbc4c4ebf97f6cca7c1f359275ed33f8b5f3e1f72e192e3dffedd35dea93b11e75"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 16:25:09.499062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:25:09.499443) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1467241263.666471-831756835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 16:25:09.583901) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 16:25:09.584238) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 16:25:09.584655) ========= ========= Total master_lag: 0.17 =========