builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1468959794.7 results: success (0) buildid: 20160719103039 builduid: 0fd81cd42ed343869304337145cb1b94 revision: 90cb06731647114f0305dd33f7dedc32d41c4661 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.695675) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.696421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.696950) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.712737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.713127) ========= 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=337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 13:23:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-07-19 13:23:14 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117512 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.850602) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.850940) ========= 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=337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016064 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.912269) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:14.912617) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 90cb06731647114f0305dd33f7dedc32d41c4661 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 90cb06731647114f0305dd33f7dedc32d41c4661 --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=337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 13:23:15,031 truncating revision to first 12 chars 2016-07-19 13:23:15,031 Setting DEBUG logging. 2016-07-19 13:23:15,032 attempt 1/10 2016-07-19 13:23:15,032 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/90cb06731647?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 13:23:15,451 unpacking tar archive at: mozilla-inbound-90cb06731647/testing/mozharness/ program finished with exit code 0 elapsedTime=0.778314 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:15.707695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:15.708475) ========= script_repo_revision: 90cb06731647114f0305dd33f7dedc32d41c4661 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:15.709427) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:15.710074) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 13:23:15.722061) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-19 13:23:15.722696) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:23:15 INFO - MultiFileLogger online at 20160719 13:23:15 in /builds/slave/test 13:23:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 13:23:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:23:15 INFO - {'append_to_log': False, 13:23:15 INFO - 'base_work_dir': '/builds/slave/test', 13:23:15 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 13:23:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:23:15 INFO - 'branch': 'Mozilla-Inbound', 13:23:15 INFO - 'buildbot_json_path': 'buildprops.json', 13:23:15 INFO - 'config_files': ('talos/linux_config.py',), 13:23:15 INFO - 'default_actions': ('clobber', 13:23:15 INFO - 'read-buildbot-config', 13:23:15 INFO - 'download-and-extract', 13:23:15 INFO - 'populate-webroot', 13:23:15 INFO - 'create-virtualenv', 13:23:15 INFO - 'install', 13:23:15 INFO - 'run-tests'), 13:23:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:23:15 INFO - 'download_minidump_stackwalk': True, 13:23:15 INFO - 'download_symbols': 'ondemand', 13:23:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:23:15 INFO - 'tooltool.py': '/tools/tooltool.py', 13:23:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:23:15 INFO - '/tools/misc-python/virtualenv.py')}, 13:23:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:23:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:23:15 INFO - 'installer_path': 'installer.exe', 13:23:15 INFO - 'log_level': 'info', 13:23:15 INFO - 'log_name': 'talos', 13:23:15 INFO - 'log_to_console': True, 13:23:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:23:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:23:15 INFO - 'opt_config_files': (), 13:23:15 INFO - 'pip_index': False, 13:23:15 INFO - 'sps_profile': False, 13:23:15 INFO - 'sps_profile_interval': 0, 13:23:15 INFO - 'suite': 'dromaeojs', 13:23:15 INFO - 'system_bits': '32', 13:23:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:23:15 INFO - 'title': 'talos-linux64-ix-060', 13:23:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:23:15 INFO - 'use_talos_json': True, 13:23:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:23:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:23:15 INFO - 'work_dir': 'build'} 13:23:15 INFO - ##### 13:23:15 INFO - ##### Running clobber step. 13:23:15 INFO - ##### 13:23:15 INFO - Running pre-action listener: _resource_record_pre_action 13:23:15 INFO - Running main action method: clobber 13:23:15 INFO - rmtree: /builds/slave/test/build 13:23:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:23:16 INFO - Running post-action listener: _resource_record_post_action 13:23:16 INFO - ##### 13:23:16 INFO - ##### Finished clobber step (success) 13:23:16 INFO - ##### 13:23:16 INFO - ##### 13:23:16 INFO - ##### Running read-buildbot-config step. 13:23:16 INFO - ##### 13:23:16 INFO - Running pre-action listener: _resource_record_pre_action 13:23:16 INFO - Running main action method: read_buildbot_config 13:23:16 INFO - Using buildbot properties: 13:23:16 INFO - { 13:23:16 INFO - "project": "", 13:23:16 INFO - "product": "firefox", 13:23:16 INFO - "script_repo_revision": "production", 13:23:16 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 13:23:16 INFO - "repository": "", 13:23:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 13:23:16 INFO - "buildid": "20160719103039", 13:23:16 INFO - "pgo_build": "True", 13:23:16 INFO - "basedir": "/builds/slave/test", 13:23:16 INFO - "buildnumber": 1366, 13:23:16 INFO - "slavename": "talos-linux64-ix-060", 13:23:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:23:16 INFO - "platform": "ubuntu64_hw", 13:23:16 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 13:23:16 INFO - "branch": "mozilla-inbound", 13:23:16 INFO - "repo_path": "integration/mozilla-inbound", 13:23:16 INFO - "revision": "90cb06731647114f0305dd33f7dedc32d41c4661", 13:23:16 INFO - "stage_platform": "linux64-pgo", 13:23:16 INFO - "builduid": "0fd81cd42ed343869304337145cb1b94", 13:23:16 INFO - "slavebuilddir": "test" 13:23:16 INFO - } 13:23:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 13:23:16 INFO - Running post-action listener: _resource_record_post_action 13:23:16 INFO - ##### 13:23:16 INFO - ##### Finished read-buildbot-config step (success) 13:23:16 INFO - ##### 13:23:16 INFO - ##### 13:23:16 INFO - ##### Running download-and-extract step. 13:23:16 INFO - ##### 13:23:16 INFO - Running pre-action listener: _resource_record_pre_action 13:23:16 INFO - Running main action method: download_and_extract 13:23:16 INFO - mkdir: /builds/slave/test/build/tests 13:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:16 INFO - https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:23:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:23:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:23:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:19 INFO - Downloaded 1361 bytes. 13:23:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:23:19 INFO - Using the following test package requirements: 13:23:19 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:23:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:23:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:23:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'jsshell-linux-x86_64.zip'], 13:23:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:23:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:23:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:23:19 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:23:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:23:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:23:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:23:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:19 INFO - https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:23:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:23:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:23:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:21 INFO - Downloaded 24337313 bytes. 13:23:21 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'] 13:23:21 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 13:23:22 INFO - Return code: 0 13:23:22 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 13:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:22 INFO - https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:23:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:23:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:23:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:25 INFO - Downloaded 24337313 bytes. 13:23: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'] 13:23: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 13:23:26 INFO - Return code: 0 13:23: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')]} 13:23:26 INFO - https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:23:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:23:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:23:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:27 INFO - Downloaded 14403317 bytes. 13:23:27 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'] 13:23:27 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 13:23:27 INFO - Return code: 0 13:23:27 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')]} 13:23:27 INFO - https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:23:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:23:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:23:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/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 13:23:29 INFO - Downloaded 60869401 bytes. 13:23:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:23:29 INFO - mkdir: /builds/slave/test/properties 13:23:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:23:29 INFO - Writing to file /builds/slave/test/properties/build_url 13:23:29 INFO - Contents: 13:23:29 INFO - build_url:https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:23:29 INFO - Running post-action listener: _resource_record_post_action 13:23:29 INFO - Running post-action listener: set_extra_try_arguments 13:23:29 INFO - ##### 13:23:29 INFO - ##### Finished download-and-extract step (success) 13:23:29 INFO - ##### 13:23:29 INFO - ##### 13:23:29 INFO - ##### Running populate-webroot step. 13:23:29 INFO - ##### 13:23:29 INFO - Running pre-action listener: _resource_record_pre_action 13:23:29 INFO - Running main action method: populate_webroot 13:23:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:23:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:23:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:23:29 INFO - '--tppagecycles', 13:23:29 INFO - '7'], 13:23:29 INFO - 'tests': ['tsvgm']}}, 13:23:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:23:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:23:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:23:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:23:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:23:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:23:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['damp', 'tps']}, 13:23:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'tests': ['damp', 'tps']}, 13:23:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['dromaeo_dom']}, 13:23:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:23:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['basic_compositor_video']}, 13:23:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:23:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['a11yr', 13:23:29 INFO - 'ts_paint', 13:23:29 INFO - 'tpaint', 13:23:29 INFO - 'sessionrestore', 13:23:29 INFO - 'sessionrestore_no_auto_restore', 13:23:29 INFO - 'tabpaint']}, 13:23:29 INFO - 'other-e10s': {'tests': ['a11yr', 13:23:29 INFO - 'ts_paint', 13:23:29 INFO - 'tpaint', 13:23:29 INFO - 'sessionrestore', 13:23:29 INFO - 'sessionrestore_no_auto_restore', 13:23:29 INFO - 'tabpaint']}, 13:23:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:23:29 INFO - 'ts_paint', 13:23:29 INFO - 'tpaint', 13:23:29 INFO - 'sessionrestore', 13:23:29 INFO - 'sessionrestore_no_auto_restore', 13:23:29 INFO - 'tabpaint']}, 13:23:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:23:29 INFO - 'ts_paint', 13:23:29 INFO - 'tpaint', 13:23:29 INFO - 'sessionrestore', 13:23:29 INFO - 'sessionrestore_no_auto_restore', 13:23:29 INFO - 'tabpaint']}, 13:23:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['a11yr', 13:23:29 INFO - 'ts_paint', 13:23:29 INFO - 'tpaint', 13:23:29 INFO - 'sessionrestore', 13:23:29 INFO - 'sessionrestore_no_auto_restore', 13:23:29 INFO - 'tabpaint']}, 13:23:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['a11yr', 13:23:29 INFO - 'ts_paint', 13:23:29 INFO - 'tpaint', 13:23:29 INFO - 'sessionrestore', 13:23:29 INFO - 'sessionrestore_no_auto_restore', 13:23:29 INFO - 'tabpaint']}, 13:23:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['tsvgx', 13:23:29 INFO - 'tsvgr_opacity', 13:23:29 INFO - 'tart', 13:23:29 INFO - 'tscrollx', 13:23:29 INFO - 'cart']}, 13:23:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:23:29 INFO - 'tsvgr_opacity', 13:23:29 INFO - 'tart', 13:23:29 INFO - 'tscrollx', 13:23:29 INFO - 'cart']}, 13:23:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'talos_options': ['--disable-e10s'], 13:23:29 INFO - 'tests': ['tp5o']}, 13:23:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'tests': ['tp5o']}, 13:23:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'talos_options': ['--disable-e10s', 13:23:29 INFO - '--xperf_path', 13:23:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:23:29 INFO - 'tests': ['tp5n']}, 13:23:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:29 INFO - 'talos_options': ['--xperf_path', 13:23:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:23:29 INFO - 'tests': ['tp5n']}}, 13:23:29 INFO - 'talos.zip': {'path': '', 13:23:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:23:29 INFO - Running post-action listener: _resource_record_post_action 13:23:29 INFO - ##### 13:23:29 INFO - ##### Finished populate-webroot step (success) 13:23:29 INFO - ##### 13:23:29 INFO - ##### 13:23:29 INFO - ##### Running create-virtualenv step. 13:23:29 INFO - ##### 13:23:29 INFO - Running pre-action listener: _resource_record_pre_action 13:23:29 INFO - Running main action method: create_virtualenv 13:23:29 INFO - Creating virtualenv /builds/slave/test/build/venv 13:23:29 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 13:23:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:23:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:23:29 INFO - Using real prefix '/usr' 13:23:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:23:30 INFO - Installing distribute.............................................................................................................................................................................................done. 13:23:32 INFO - Installing pip.................done. 13:23:32 INFO - Return code: 0 13:23:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:23:32 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')]} 13:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:32 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')]} 13:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:32 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:32 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 13:23:32 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 13:23:32 INFO - Using env: {'DISPLAY': ':0', 13:23:32 INFO - 'HOME': '/home/cltbld', 13:23:32 INFO - 'LANG': 'en_US.UTF-8', 13:23:32 INFO - 'LANGUAGE': 'en_US:en', 13:23:32 INFO - 'LOGNAME': 'cltbld', 13:23:32 INFO - 'MAIL': '/var/mail/cltbld', 13:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:32 INFO - 'MOZ_NO_REMOTE': '1', 13:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:32 INFO - 'NO_EM_RESTART': '1', 13:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:32 INFO - 'PWD': '/builds/slave/test', 13:23:32 INFO - 'SHELL': '/bin/bash', 13:23:32 INFO - 'SHLVL': '1', 13:23:32 INFO - 'TERM': 'linux', 13:23:32 INFO - 'TMOUT': '86400', 13:23:32 INFO - 'USER': 'cltbld', 13:23:32 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:32 INFO - '_': '/tools/buildbot/bin/python'} 13:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:32 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 13:23:32 INFO - Cleaning up... 13:23:32 INFO - Return code: 0 13:23:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:23:32 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')]} 13:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:32 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')]} 13:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:32 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:32 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 13:23:32 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 13:23:32 INFO - Using env: {'DISPLAY': ':0', 13:23:32 INFO - 'HOME': '/home/cltbld', 13:23:32 INFO - 'LANG': 'en_US.UTF-8', 13:23:32 INFO - 'LANGUAGE': 'en_US:en', 13:23:32 INFO - 'LOGNAME': 'cltbld', 13:23:32 INFO - 'MAIL': '/var/mail/cltbld', 13:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:32 INFO - 'MOZ_NO_REMOTE': '1', 13:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:32 INFO - 'NO_EM_RESTART': '1', 13:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:32 INFO - 'PWD': '/builds/slave/test', 13:23:32 INFO - 'SHELL': '/bin/bash', 13:23:32 INFO - 'SHLVL': '1', 13:23:32 INFO - 'TERM': 'linux', 13:23:32 INFO - 'TMOUT': '86400', 13:23:32 INFO - 'USER': 'cltbld', 13:23:32 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:32 INFO - '_': '/tools/buildbot/bin/python'} 13:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:32 INFO - Downloading/unpacking psutil>=3.1.1 13:23:32 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 13:23:32 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 13:23:32 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 13:23:32 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 13:23:32 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 13:23:32 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 13:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:23:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:23:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:23:34 INFO - Installing collected packages: psutil 13:23:34 INFO - Running setup.py install for psutil 13:23:34 INFO - building 'psutil._psutil_linux' extension 13:23:34 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 13:23:35 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 13:23:35 INFO - building 'psutil._psutil_posix' extension 13:23:35 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 13:23:35 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 13:23:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:23:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:23:35 INFO - Successfully installed psutil 13:23:35 INFO - Cleaning up... 13:23:35 INFO - Return code: 0 13:23:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:23: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')]} 13:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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')]} 13:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:35 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:35 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 13:23:35 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 13:23:35 INFO - Using env: {'DISPLAY': ':0', 13:23:35 INFO - 'HOME': '/home/cltbld', 13:23:35 INFO - 'LANG': 'en_US.UTF-8', 13:23:35 INFO - 'LANGUAGE': 'en_US:en', 13:23:35 INFO - 'LOGNAME': 'cltbld', 13:23:35 INFO - 'MAIL': '/var/mail/cltbld', 13:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:35 INFO - 'MOZ_NO_REMOTE': '1', 13:23:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:35 INFO - 'NO_EM_RESTART': '1', 13:23:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:35 INFO - 'PWD': '/builds/slave/test', 13:23:35 INFO - 'SHELL': '/bin/bash', 13:23:35 INFO - 'SHLVL': '1', 13:23:35 INFO - 'TERM': 'linux', 13:23:35 INFO - 'TMOUT': '86400', 13:23:35 INFO - 'USER': 'cltbld', 13:23:35 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:35 INFO - '_': '/tools/buildbot/bin/python'} 13:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:23:35 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 13:23:35 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 13:23:35 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 13:23:35 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 13:23:35 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 13:23:35 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 13:23:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:23:37 INFO - Installing collected packages: mozsystemmonitor 13:23:37 INFO - Running setup.py install for mozsystemmonitor 13:23:37 INFO - Successfully installed mozsystemmonitor 13:23:37 INFO - Cleaning up... 13:23:37 INFO - Return code: 0 13:23:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:37 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:37 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 13:23:37 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 13:23:37 INFO - Using env: {'DISPLAY': ':0', 13:23:37 INFO - 'HOME': '/home/cltbld', 13:23:37 INFO - 'LANG': 'en_US.UTF-8', 13:23:37 INFO - 'LANGUAGE': 'en_US:en', 13:23:37 INFO - 'LOGNAME': 'cltbld', 13:23:37 INFO - 'MAIL': '/var/mail/cltbld', 13:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:37 INFO - 'MOZ_NO_REMOTE': '1', 13:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:37 INFO - 'NO_EM_RESTART': '1', 13:23:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:37 INFO - 'PWD': '/builds/slave/test', 13:23:37 INFO - 'SHELL': '/bin/bash', 13:23:37 INFO - 'SHLVL': '1', 13:23:37 INFO - 'TERM': 'linux', 13:23:37 INFO - 'TMOUT': '86400', 13:23:37 INFO - 'USER': 'cltbld', 13:23:37 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:37 INFO - '_': '/tools/buildbot/bin/python'} 13:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:37 INFO - Downloading/unpacking jsonschema==2.5.1 13:23:37 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 13:23:37 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 13:23:37 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 13:23:37 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 13:23:37 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 13:23:37 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 13:23:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:23:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:23:39 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 13:23:39 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 13:23:39 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 13:23:39 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 13:23:39 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 13:23:39 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 13:23:40 INFO - Downloading functools32-3.2.3-2.tar.gz 13:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:23:40 INFO - warning: no files found matching '*.txt' 13:23:40 INFO - no previously-included directories found matching 'build' 13:23:40 INFO - no previously-included directories found matching 'dist' 13:23:40 INFO - no previously-included directories found matching '.git*' 13:23:40 INFO - Installing collected packages: jsonschema, functools32 13:23:40 INFO - Running setup.py install for functools32 13:23:40 INFO - warning: no files found matching '*.txt' 13:23:40 INFO - no previously-included directories found matching 'build' 13:23:40 INFO - no previously-included directories found matching 'dist' 13:23:40 INFO - no previously-included directories found matching '.git*' 13:23:40 INFO - Successfully installed jsonschema functools32 13:23:40 INFO - Cleaning up... 13:23:40 INFO - Return code: 0 13:23:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:40 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:40 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 13:23:40 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 13:23:40 INFO - Using env: {'DISPLAY': ':0', 13:23:40 INFO - 'HOME': '/home/cltbld', 13:23:40 INFO - 'LANG': 'en_US.UTF-8', 13:23:40 INFO - 'LANGUAGE': 'en_US:en', 13:23:40 INFO - 'LOGNAME': 'cltbld', 13:23:40 INFO - 'MAIL': '/var/mail/cltbld', 13:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:40 INFO - 'MOZ_NO_REMOTE': '1', 13:23:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:40 INFO - 'NO_EM_RESTART': '1', 13:23:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:40 INFO - 'PWD': '/builds/slave/test', 13:23:40 INFO - 'SHELL': '/bin/bash', 13:23:40 INFO - 'SHLVL': '1', 13:23:40 INFO - 'TERM': 'linux', 13:23:40 INFO - 'TMOUT': '86400', 13:23:40 INFO - 'USER': 'cltbld', 13:23:40 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:40 INFO - '_': '/tools/buildbot/bin/python'} 13:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:40 INFO - Downloading/unpacking blobuploader==1.2.4 13:23:40 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 13:23:40 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 13:23:40 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 13:23:40 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 13:23:40 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 13:23:40 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 13:23:42 INFO - Downloading blobuploader-1.2.4.tar.gz 13:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:23:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:23:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 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 13:23:42 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 13:23:43 INFO - Downloading docopt-0.6.1.tar.gz 13:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:23:43 INFO - Installing collected packages: blobuploader, requests, docopt 13:23:43 INFO - Running setup.py install for blobuploader 13:23:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:23:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:23:43 INFO - Running setup.py install for requests 13:23:43 INFO - Running setup.py install for docopt 13:23:43 INFO - Successfully installed blobuploader requests docopt 13:23:43 INFO - Cleaning up... 13:23:43 INFO - Return code: 0 13:23:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:23: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')]} 13:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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')]} 13:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:43 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:43 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 13:23:43 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 13:23:43 INFO - Using env: {'DISPLAY': ':0', 13:23:43 INFO - 'HOME': '/home/cltbld', 13:23:43 INFO - 'LANG': 'en_US.UTF-8', 13:23:43 INFO - 'LANGUAGE': 'en_US:en', 13:23:43 INFO - 'LOGNAME': 'cltbld', 13:23:43 INFO - 'MAIL': '/var/mail/cltbld', 13:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:43 INFO - 'MOZ_NO_REMOTE': '1', 13:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:43 INFO - 'NO_EM_RESTART': '1', 13:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:43 INFO - 'PWD': '/builds/slave/test', 13:23:43 INFO - 'SHELL': '/bin/bash', 13:23:43 INFO - 'SHLVL': '1', 13:23:43 INFO - 'TERM': 'linux', 13:23:43 INFO - 'TMOUT': '86400', 13:23:43 INFO - 'USER': 'cltbld', 13:23:43 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:43 INFO - '_': '/tools/buildbot/bin/python'} 13:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:23:44 INFO - Running setup.py (path:/tmp/pip-0XP05V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:23:44 INFO - Running setup.py (path:/tmp/pip-F5gOw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:23:44 INFO - Running setup.py (path:/tmp/pip-eGpYxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:23:44 INFO - Running setup.py (path:/tmp/pip-p7JsO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:23:44 INFO - Running setup.py (path:/tmp/pip-2U3pxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:23:44 INFO - Running setup.py (path:/tmp/pip-rn3oW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:23:44 INFO - Running setup.py (path:/tmp/pip-YHbFjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:23:44 INFO - Running setup.py (path:/tmp/pip-US8WAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:23:44 INFO - Running setup.py (path:/tmp/pip-IMSjpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:23:44 INFO - Running setup.py (path:/tmp/pip-T4FowN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:23:44 INFO - Running setup.py (path:/tmp/pip-ps5Pey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:23:44 INFO - Running setup.py (path:/tmp/pip-bCvRA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:23:44 INFO - Running setup.py (path:/tmp/pip-cJv_3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:23:45 INFO - Running setup.py (path:/tmp/pip-B8J_C6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:45 INFO - Running setup.py (path:/tmp/pip-4DERhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:23:45 INFO - Running setup.py (path:/tmp/pip-3eUg15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:23:45 INFO - Running setup.py (path:/tmp/pip-q5yVZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:23:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:23:45 INFO - Running setup.py install for manifestparser 13:23:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:23:45 INFO - Running setup.py install for mozcrash 13:23:45 INFO - Running setup.py install for mozdebug 13:23:45 INFO - Running setup.py install for mozdevice 13:23:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:23:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:23:45 INFO - Running setup.py install for mozfile 13:23:45 INFO - Running setup.py install for mozhttpd 13:23:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:23:45 INFO - Running setup.py install for mozinfo 13:23:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Running setup.py install for mozInstall 13:23:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Running setup.py install for mozleak 13:23:46 INFO - Running setup.py install for mozlog 13:23:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Running setup.py install for moznetwork 13:23:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Running setup.py install for mozprocess 13:23:46 INFO - Running setup.py install for mozprofile 13:23:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Running setup.py install for mozrunner 13:23:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:23:46 INFO - Running setup.py install for mozscreenshot 13:23:46 INFO - Running setup.py install for moztest 13:23:46 INFO - Running setup.py install for mozversion 13:23:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:23:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:23:47 INFO - Cleaning up... 13:23:47 INFO - Return code: 0 13:23:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:47 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:47 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 13:23:47 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 13:23:47 INFO - Using env: {'DISPLAY': ':0', 13:23:47 INFO - 'HOME': '/home/cltbld', 13:23:47 INFO - 'LANG': 'en_US.UTF-8', 13:23:47 INFO - 'LANGUAGE': 'en_US:en', 13:23:47 INFO - 'LOGNAME': 'cltbld', 13:23:47 INFO - 'MAIL': '/var/mail/cltbld', 13:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:47 INFO - 'MOZ_NO_REMOTE': '1', 13:23:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:47 INFO - 'NO_EM_RESTART': '1', 13:23:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:47 INFO - 'PWD': '/builds/slave/test', 13:23:47 INFO - 'SHELL': '/bin/bash', 13:23:47 INFO - 'SHLVL': '1', 13:23:47 INFO - 'TERM': 'linux', 13:23:47 INFO - 'TMOUT': '86400', 13:23:47 INFO - 'USER': 'cltbld', 13:23:47 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:47 INFO - '_': '/tools/buildbot/bin/python'} 13:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:23:47 INFO - Running setup.py (path:/tmp/pip-wZnp83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:23:47 INFO - Running setup.py (path:/tmp/pip-lu8kP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:23:47 INFO - Running setup.py (path:/tmp/pip-IgshUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:23:47 INFO - Running setup.py (path:/tmp/pip-jkCaRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:23:47 INFO - Running setup.py (path:/tmp/pip-crSd0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:23:47 INFO - Running setup.py (path:/tmp/pip-suCOkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:23:47 INFO - Running setup.py (path:/tmp/pip-4pHyUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:23:47 INFO - Running setup.py (path:/tmp/pip-3SsPr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:23:47 INFO - Running setup.py (path:/tmp/pip-5gIWtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:23:47 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)) 13:23:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:23:47 INFO - Running setup.py (path:/tmp/pip-sbcg4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:23:48 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)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:23:48 INFO - Running setup.py (path:/tmp/pip-_CUDhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:23:48 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)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:23:48 INFO - Running setup.py (path:/tmp/pip-phkWhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:23:48 INFO - Running setup.py (path:/tmp/pip-LrM6aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:23:48 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)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:23:48 INFO - Running setup.py (path:/tmp/pip-9IeHsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:48 INFO - Running setup.py (path:/tmp/pip-nQK5QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:48 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)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:23:48 INFO - Running setup.py (path:/tmp/pip-WZh2z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:23:48 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)) 13:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:23:48 INFO - Running setup.py (path:/tmp/pip-9wOBpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:23:48 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)) 13:23:48 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)) 13:23:48 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)) 13:23:48 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)) 13:23:48 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)) 13:23:48 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)) 13:23:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:23: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 13:23: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 13:23: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 13:23: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 13:23: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 13:23: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 13:23:50 INFO - Downloading blessings-1.6.tar.gz 13:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:23:50 INFO - Installing collected packages: blessings 13:23:50 INFO - Running setup.py install for blessings 13:23:50 INFO - Successfully installed blessings 13:23:50 INFO - Cleaning up... 13:23:50 INFO - Return code: 0 13:23:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:23:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:23:50 INFO - Reading from file tmpfile_stdout 13:23:50 INFO - Current package versions: 13:23:50 INFO - argparse == 1.2.1 13:23:50 INFO - blessings == 1.6 13:23:50 INFO - blobuploader == 1.2.4 13:23:50 INFO - docopt == 0.6.1 13:23:50 INFO - functools32 == 3.2.3-2 13:23:50 INFO - jsonschema == 2.5.1 13:23:50 INFO - manifestparser == 1.1 13:23:50 INFO - mozInstall == 1.12 13:23:50 INFO - mozcrash == 0.17 13:23:50 INFO - mozdebug == 0.1 13:23:50 INFO - mozdevice == 0.48 13:23:50 INFO - mozfile == 1.2 13:23:50 INFO - mozhttpd == 0.7 13:23:50 INFO - mozinfo == 0.9 13:23:50 INFO - mozleak == 0.1 13:23:50 INFO - mozlog == 3.2 13:23:50 INFO - moznetwork == 0.27 13:23:50 INFO - mozprocess == 0.23 13:23:50 INFO - mozprofile == 0.28 13:23:50 INFO - mozrunner == 6.12 13:23:50 INFO - mozscreenshot == 0.1 13:23:50 INFO - mozsystemmonitor == 0.3 13:23:50 INFO - moztest == 0.7 13:23:50 INFO - mozversion == 1.4 13:23:50 INFO - psutil == 3.1.1 13:23:50 INFO - requests == 1.2.3 13:23:50 INFO - wsgiref == 0.1.2 13:23:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:23:50 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')]} 13:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:50 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')]} 13:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:50 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 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23:50 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 13:23:50 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 13:23:50 INFO - Using env: {'DISPLAY': ':0', 13:23:50 INFO - 'HOME': '/home/cltbld', 13:23:50 INFO - 'LANG': 'en_US.UTF-8', 13:23:50 INFO - 'LANGUAGE': 'en_US:en', 13:23:50 INFO - 'LOGNAME': 'cltbld', 13:23:50 INFO - 'MAIL': '/var/mail/cltbld', 13:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:50 INFO - 'MOZ_NO_REMOTE': '1', 13:23:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:50 INFO - 'NO_EM_RESTART': '1', 13:23:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:50 INFO - 'PWD': '/builds/slave/test', 13:23:50 INFO - 'SHELL': '/bin/bash', 13:23:50 INFO - 'SHLVL': '1', 13:23:50 INFO - 'TERM': 'linux', 13:23:50 INFO - 'TMOUT': '86400', 13:23:50 INFO - 'USER': 'cltbld', 13:23:50 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:50 INFO - '_': '/tools/buildbot/bin/python'} 13:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 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)) 13:23:51 INFO - Cleaning up... 13:23:51 INFO - Return code: 0 13:23:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:23: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')]} 13:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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')]} 13:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1114180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, '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': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', '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 13:23: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', 'jsonschema'] in /builds/slave/test/build 13:23: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 jsonschema 13:23:51 INFO - Using env: {'DISPLAY': ':0', 13:23:51 INFO - 'HOME': '/home/cltbld', 13:23:51 INFO - 'LANG': 'en_US.UTF-8', 13:23:51 INFO - 'LANGUAGE': 'en_US:en', 13:23:51 INFO - 'LOGNAME': 'cltbld', 13:23:51 INFO - 'MAIL': '/var/mail/cltbld', 13:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:51 INFO - 'MOZ_NO_REMOTE': '1', 13:23:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:23:51 INFO - 'NO_EM_RESTART': '1', 13:23:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:51 INFO - 'PWD': '/builds/slave/test', 13:23:51 INFO - 'SHELL': '/bin/bash', 13:23:51 INFO - 'SHLVL': '1', 13:23:51 INFO - 'TERM': 'linux', 13:23:51 INFO - 'TMOUT': '86400', 13:23:51 INFO - 'USER': 'cltbld', 13:23:51 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:51 INFO - '_': '/tools/buildbot/bin/python'} 13:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:23:51 INFO - Cleaning up... 13:23:51 INFO - Return code: 0 13:23:51 INFO - Running post-action listener: _resource_record_post_action 13:23:51 INFO - Running post-action listener: _start_resource_monitoring 13:23:51 INFO - Starting resource monitoring. 13:23:51 INFO - ##### 13:23:51 INFO - ##### Finished create-virtualenv step (success) 13:23:51 INFO - ##### 13:23:51 INFO - ##### 13:23:51 INFO - ##### Running install step. 13:23:51 INFO - ##### 13:23:51 INFO - Running pre-action listener: _resource_record_pre_action 13:23:51 INFO - Running main action method: install 13:23:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:23:51 INFO - Reading from file tmpfile_stdout 13:23:51 INFO - Detecting whether we're running mozinstall >=1.0... 13:23:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:23:51 INFO - Reading from file tmpfile_stdout 13:23:51 INFO - Output received: 13:23:51 INFO - Usage: mozinstall [options] installer 13:23:51 INFO - Options: 13:23:51 INFO - -h, --help show this help message and exit 13:23:51 INFO - -d DEST, --destination=DEST 13:23:51 INFO - Directory to install application into. [default: 13:23:51 INFO - "/builds/slave/test"] 13:23:51 INFO - --app=APP Application being installed. [default: firefox] 13:23:51 INFO - mkdir: /builds/slave/test/build/application 13:23:51 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'] 13:23:51 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 13:24:08 INFO - Reading from file tmpfile_stdout 13:24:08 INFO - Output received: 13:24:08 INFO - /builds/slave/test/build/application/firefox/firefox 13:24:08 INFO - Running post-action listener: _resource_record_post_action 13:24:08 INFO - ##### 13:24:08 INFO - ##### Finished install step (success) 13:24:08 INFO - ##### 13:24:08 INFO - ##### 13:24:08 INFO - ##### Running run-tests step. 13:24:08 INFO - ##### 13:24:08 INFO - Running pre-action listener: _resource_record_pre_action 13:24:08 INFO - Running main action method: run_tests 13:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:24:08 INFO - Python 2.7.3 13:24:08 INFO - Return code: 0 13:24:08 INFO - grabbing minidump binary from tooltool 13:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:08 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 0x11123b0>, '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 0x11132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1113740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:24:08 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 13:24:08 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 13:24:08 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 13:24:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:24:08 INFO - Return code: 0 13:24:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:24:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:24:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:24:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:24:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 13:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 13:24:08 INFO - Using env: {'DISPLAY': ':0', 13:24:08 INFO - 'HOME': '/home/cltbld', 13:24:08 INFO - 'LANG': 'en_US.UTF-8', 13:24:08 INFO - 'LANGUAGE': 'en_US:en', 13:24:08 INFO - 'LOGNAME': 'cltbld', 13:24:08 INFO - 'MAIL': '/var/mail/cltbld', 13:24:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:24:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:08 INFO - 'MOZ_NO_REMOTE': '1', 13:24:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:24:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:08 INFO - 'NO_EM_RESTART': '1', 13:24:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:08 INFO - 'PWD': '/builds/slave/test', 13:24:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:24:08 INFO - 'SHELL': '/bin/bash', 13:24:08 INFO - 'SHLVL': '1', 13:24:08 INFO - 'TERM': 'linux', 13:24:08 INFO - 'TMOUT': '86400', 13:24:08 INFO - 'USER': 'cltbld', 13:24:08 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041', 13:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:08 INFO - '_': '/tools/buildbot/bin/python'} 13:24:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 13:24:09 INFO - mozversion application_buildid: 20160719103039 13:24:09 INFO - mozversion application_changeset: 90cb06731647114f0305dd33f7dedc32d41c4661 13:24:09 INFO - mozversion application_display_name: Nightly 13:24:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:24:09 INFO - mozversion application_name: Firefox 13:24:09 INFO - mozversion application_remotingname: firefox 13:24:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 13:24:09 INFO - mozversion application_vendor: Mozilla 13:24:09 INFO - mozversion application_version: 50.0a1 13:24:09 INFO - mozversion platform_buildid: 20160719103039 13:24:09 INFO - mozversion platform_changeset: 90cb06731647114f0305dd33f7dedc32d41c4661 13:24:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 13:24:09 INFO - mozversion platform_version: 50.0a1 13:24:09 INFO - using testdate: 1468959849 13:24:09 INFO - actual date: 1468959849 13:24:09 INFO - starting webserver on 'localhost:46809' 13:24:09 INFO - SUITE-START | Running 2 tests 13:24:09 INFO - TEST-START | dromaeo_css 13:24:09 INFO - operating with platform_type : linux_ 13:24:09 INFO - Initialising browser for dromaeo_css test... 13:24:09 INFO - TEST-INFO | started process 29017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphdPS90/profile http://localhost:46809/getInfo.html) 13:24:13 INFO - PROCESS | 29017 | __metrics Screen width/height:1600/1200 13:24:13 INFO - PROCESS | 29017 | colorDepth:24 13:24:13 INFO - PROCESS | 29017 | Browser inner width/height: 1024/697 13:24:13 INFO - PROCESS | 29017 | __metrics 13:24:14 INFO - PROCESS | 29017 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:24:14 INFO - PROCESS | 29017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:24:14 INFO - TEST-INFO | 29017: exit 0 13:24:14 INFO - Browser initialized. 13:24:14 INFO - Running cycle 1/1 for dromaeo_css test... 13:24:14 INFO - TEST-INFO | started process 29101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphdPS90/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:24:26 INFO - PROCESS | 29101 | RSS: Main: 157790208 13:24:26 INFO - PROCESS | 29101 | 13:25:18 INFO - PROCESS | 29101 | 1468959918194 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] 13:27:25 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46809/tests/dromaeo/cssquery-ext.html) 13:27:26 INFO - PROCESS | 29101 | RSS: Main: 239714304 13:27:26 INFO - PROCESS | 29101 | 13:30:29 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-ext.html (next: http://localhost:46809/tests/dromaeo/cssquery-jquery.html) 13:30:29 INFO - PROCESS | 29101 | RSS: Main: 248164352 13:30:29 INFO - PROCESS | 29101 | 13:33:31 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46809/tests/dromaeo/cssquery-mootools.html) 13:33:32 INFO - PROCESS | 29101 | RSS: Main: 255406080 13:33:32 INFO - PROCESS | 29101 | 13:36:34 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46809/tests/dromaeo/cssquery-prototype.html) 13:36:35 INFO - PROCESS | 29101 | RSS: Main: 248639488 13:36:35 INFO - PROCESS | 29101 | 13:39:37 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46809/tests/dromaeo/cssquery-yui.html) 13:39:38 INFO - PROCESS | 29101 | RSS: Main: 223678464 13:39:38 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-yui.html (next: http://localhost:46809/tests/dromaeo/cssquery-dojo.html) 13:42:41 INFO - PROCESS | 29101 | RSS: Main: 277868544 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | __start_tp_report 13:42:41 INFO - PROCESS | 29101 | _x_x_mozilla_page_load 13:42:41 INFO - PROCESS | 29101 | _x_x_mozilla_page_load_details 13:42:41 INFO - PROCESS | 29101 | |i|pagename|runs| 13:42:41 INFO - PROCESS | 29101 | |0;dojo.html;3027.6064111665487;3042.771792115592;3087.4442600331945;3089.0887188279457;3101.5967924169854;5184.922226166601;5236.92144617831;5282.6830390176565;5401.081816091264;5427.701476418801;5221.347331583552;5229.032628963635;5290.973545132269;5300.575953923687;5302.416734159239;4757.833475828338;4794.616430685554;4844.418669759634;4855.5872750816225;5055.216441451581;4956.950430495703;4966.25506174075;4967.850964471072;5020.347354843865;5028.371453568304;4754.881127971811;4820.228763397857;4822.300766388889;4873.585745211662;4876.512348765131;4782.402199725035;4807.78364973577;4814.638902082323;4855.781489832966;4906.803727850882;4832.951670483285;4848.345473361086;4850.199717046683;4852.805887764521;4854.2718592211095;5186.299849520305;5293.179557168645;5341.092014357569;5428.294321738188;5430.239766432703;5775.537956963432;5781.075027995501;5801.53587712982;5826.621269617462;5829.417058294179;5586.608937374385;5636.633618814764;5849.473547380713;5871.089981052944;5871.324797648283;5317.282166907496;5383.973080134574;5401.378841433247;5408.5673146149;5412.756425960802;5913.201717768132;5935.73289201987;5938.168656388109;5940.198073260141;5945.881082378328;5443.537299329601;5472.945270547243;5474.589405794582;5629.2681951346885;5656.802011929645;5657.688827114548;5661.46216109469;5661.66030038199;5664.320281566156;5664.801731939445;5581.916271255935;5600.075987462104;5602.4957753802355;5605.523530499871;5606.187102870061;5518.227448157261;5624.437556244425;5627.8593035174445;5632.183333416631;5634.070378387602;4353.455818022747;4379.978100109479;4387.802548885308;4423.336499525032;4427.490838553543;3883.728139030241;3894.571597124314;3895.7467764595776;3897.434871943609;3902.570717221103;5328.147496400557;5391.191321301836;5417.83746487606;5455.563554915597;5479.890402191933;5736.282964629421;5822;5825.446582574648;5869.911951320733;5876.529877609781;5496.230527726121;5499.7250137492965;5501.669899806025;5507.476789704971;5508.366537848118;4837.419509658864;4994.875128121826;4995.9500404996215;5001.24981252815;5030.4718004608985;5884.852878677982;5925.674087925206;5944.138099975566;5964.821055368346;5984.012637914782;5797.2173756542325;5799.710014499343;5800.216970708901;5812.593118481665;5813.79651712188;5746.5402767779315;5768.625039372428;5962.25471816023;5987.251593550806;5987.970060149672;5528.336599608073;5538.501534861882;5575.721213939368;5581.051221292471;5634.74643641029;5673.120666296731;5675.971620142038;5677.74450149736;5678.290213723285;5692.971535142297;5490.890182196254;5494.038543254995;5519.862003449946;5524.337079550319;5558.249636299048;5592.27300450955;5595.8880822384135;5597.356304024926;5598.10430331145;5633.352164501135;5532.059549876451;5536.086545719912;5536.501714845684;5536.778528858962;5542.584306177134;5401.6218864679095;5499.532539734168;5516.696581687886;5552.888942221052;5663.3770285267765;5428.402875683751;5436.157395603688;5437.945620543744;5443.210734443563;5444.50999410055 13:42:41 INFO - PROCESS | 29101 | |1;ext.html;594.1592646405337;631.981040568783;636.5480508838724;643.5913195121096;659.396652063362;30234.244143896412;43419.78290108549;43989.7800510997;44043.77978110105;44067.55932440675;46567.76716116415;46871.765641171834;46959.76520117403;47078;47104.764476177654;9049.728508144766;9189.264858811279;9190.632374705037;9194.172524472797;9200;9056;9143.954280228589;9150.588223529958;9162.541872906362;9163.037881022497;9199.172074513292;9231.815363692722;9234.307426943005;9238.86141707875;9246;9141.131592498736;9166.26669866409;9171.954140229256;9185.40294880834;9185.908140918606;9018.188363047291;9136.543172841331;9141.131592498701;9142.085791420872;9144.222741067017;43324.350134748005;43459.34810977838;43508.782456087836;43582.128357432994;43682.78158609186;3425.880094196716;3517.1383011162175;3545.2732189901008;3549.219171782204;3646.179609587848;33113.6688633113;33191.66808331934;33262.168445788804;33570.664293357;34620.13449663753;4357.956420435787;4487.932681009787;4496.3705081288645;4507.481639611454;4536.455625324949;363033;364777;366105;367309;367943;53132.734336328074;60849;61783.382166178664;61920;61980;61946;63225.72904115994;63402.36597634057;69842;69934.65032674804;16215.35138594443;16375.83624163767;16464;16486.505404837873;16503.257353419125;11265.605703800327;11504.367259800623;11804.822927656092;11834.940825295818;11844.822327665092;17893;17935.37226197077;18395.90802046008;18654.626907461774;18913.43259702211;6751.594904305757;6776.76281330161;6876.518643694993;6958.930410695828;7024;930.9534523273809;1009.8990100989843;1070.1255779922528;1147.311613032174;1154.1228666213738;33035.339293214;34352;34362.82818585941;34467.827660861534;34643;1116.4529380603446;1118.2395970739974;1178.393127539318;1206.7405507815686;1208.6917835951776;17676.91161544184;17845.643087138185;17947.192376343097;18016.459506214833;18110.818891811177;570409;572476;572829;573756;574012;60718.392816072155;60936.39063609396;61002.38997610056;61090.083648745305;61106.694466527384;1240.3302216803006;1343.147101590497;1370.9108907920954;1402.3689339797131;1402.7825687018324;33435.83282083574;34917.825410872785;35016.299674006885;35043;35258.47112293369;52377.47622523726;56368.87262254814;57998.7100064497;58100;64135.35864641294;402429;402692;403151;403255;405989;25211.49577008486;27934;28631.421072365953;28895.277618058877;29115.126546203646;49395.50604493909;50106;50272.245916312015;50365;51206;41758.37362439626;42585;42901.78549107235;43198.35202472028;43527.34708979431;42196.367054493596;42289.78855105705;42357.36463952981;42369.15261694811;43285.13429731325;39181.80409097936;39365.80317098396;40355.79822100871;43511;43544.34683479814;35752.463713043806;37608.623913760515;38076.42885356777;38243.23513529769;38912;28697.856510717313;28925;29053;29053.418931621673;29070 13:42:41 INFO - PROCESS | 29101 | |2;jquery.html;392.9096307849193;393.16060211448547;394.9940750888736;400.5353789604059;422.1662217121186;13396.67733107195;18964.525886852836;20365;20480.385588432335;20543.280985165522;14646.34091465884;19396.933168675718;19438;19877.403677889688;20336.084876180572;5823.67969761663;6003.159557661931;6007.789727359544;6020.759169633209;6038.849028774272;5946.286445626531;6009.248843894513;6011.729472173763;6016.0975853622085;6019.1272265521475;4217.957820421802;4731.739754313511;6655.900161497581;6662.733490660392;6792.558483698544;6485.6108633482145;6725.39471447572;6736.730530778763;6764.289749576297;6770.119884415019;5876.882462350772;5900.911486327708;5909.556783241274;5913.408659134096;5916.349201587822;23816.761832381802;24296.1496347627;25096.12163574266;25212.369690757696;25295.62056569153;2426.6360045993065;2726.754592086702;3002;3121.094882484077;3149.3543823516125;14939.925300373428;14972.17653029083;15088.471903483434;15204.543863684106;15500.612484687861;1772.2556526259002;1869.3457289948544;1934.429343343717;1988.741463609736;2031.177373163871;79746.20253797446;82137.58931205365;93221.94057386316;103591.4820425893;106218.93781062168;16881.662366752596;18518.907405462887;18635;18760.906195468935;18862.62274754497;32553.83723081417;32892.1776955578;32903.3419331612;33051;33088.66911330856;9664.806703865883;10063.496825158712;10234.437105959094;10278.074973252444;10483;8425.789355266168;8546.102659220816;8632.827343453097;8735.43219690718;8739.956300218459;7450.970594848455;8670.826583468295;9055.275577953747;9134.954325228331;9440.90559094414;3486.7036301914113;3619.7647152934974;3643.635636436335;3657.0767327720278;3658.34149853029;960.6973803251954;979.6913972098766;1039.880413752413;1048.85840411545;1079.09895237476;20661.793382066287;21228.469288267916;21266.468338291666;21279.468013299793;21370.02166555185;980.9950950245346;987.8024395121005;1000.3847633705219;1010.2978429991085;1050.222835102019;11280.548778048951;11683.299002059903;11767.882051205122;11779.411029448493;11782.823257651142;496940;497197;506323;507014;510395;68139;101103.4944825286;101359;101591;101952;978.8433850583872;1006.605850896499;1015.6242190389455;1037.6160820496464;1039.8284283093205;18342.72485912739;18375.448736537925;18739.53151171177;18854.389189726593;18930.621387571897;41750;42512.78743606262;43001.78499107484;44073.67778966636;44123.77938110289;57398.42601573931;59070.40929590821;59739;60246;60459.69770151121;7650.387968962358;7684.961575192088;7737.845243095219;8466.153384661484;8717.738467845975;9039.63841446361;9279.443233406017;9503.572339244889;9634;10159.542820572944;8276.627551760306;8563.785905352415;8604.956975215084;8683.305335573265;9077.046910074347;8359.122292159236;8622.137786221328;8713.085126061673;8756.693515726918;9043.050479699537;8946.86579701318;8989.325800565115;9076.455412675261;9083.45499270046;9108.407953483002;13015.934920325337;13413.99395045395;13436.664083397602;13540.86459135396;13552.119112257671;12543.12198146121;12662.556810511587;12883.42024608876;12987.610371688865;13080.542181023618 13:42:41 INFO - PROCESS | 29101 | |3;mootools.html;1259.6410023143405;1435.5549779568332;1475.8376578576356;1579.5656194546498;1742.128935532233;8379;8639.65441382344;8651.30789536837;8651.870221946669;8959.731208063753;8234.794130146749;8484.91515084849;8681.131886811332;8682.696105636303;8691.956540217307;2010.1858747207405;2080.3758872338312;2085.708000879878;2155.8059774620283;2203.823694104468;2104.8947552622385;2106.220698341608;2294.403455101677;2354.1407386303963;2358.492924021335;2285.257291380308;2330.382448651109;2347.3779448446176;2347.5187586544716;2348.424635964191;1927.0750039980887;2216.4791274050585;2218.9778102219093;2220.0342850859765;2221.855579387343;2184.4757258258064;2230.654248591471;2234.821214302868;2252.6057939860457;2262.7058482397183;8475.067742548315;8510.319174466027;8524.57377131141;8529.957350213272;8532.957335213285;5111.284420181178;5209.609279304067;5211.426743058299;5213.843584692428;5218.243354713583;3239.5626590410466;3336.4661654135466;3372.5278461015478;3394.1514621344663;3396.6053931978145;1090.8963648453384;1173.683105561502;1183.9704007399798;2168.913243470259;2187.1798075721604;12693.492260309577;12952.935235323952;12957.157784743962;12970.156939798977;12974.026947978939;4749.201689839126;4763.642726795504;4782.904341913142;4842.782074806642;4925.359703238556;10759.83860242097;10761.246712730033;10767.89232107669;10795.028447439769;10810.729731756768;5444.7822087116865;5477.808276710296;5503.807366742145;5511.200875872976;5551.916721249185;5017.874553136201;5067.771950262173;5074.923876141861;5112.590992720573;5129.204973229081;4032.4152997815154;4052.8378864845668;4105.261053010427;4138.813753381105;4150.3566947123245;1261.678272040642;1280.929548874802;1305.8171855940177;1307.3593938969836;1317.8748018938186;882.139913584263;924.1128516623982;974.2887691984749;983.0759086458706;998.2612866478898;6185.752569897154;6324.683765811691;6459.128017717642;6479.262075735933;6514.967425162844;428.88205743420184;430.79106633282834;431.8747563206635;450.7115446114489;453.1005953182929;4144.48193975753;4453.131639330364;4456.64346852251;4489.506154323022;4507.4365704286965;10821.404822734825;11203.831942520868;11277.379744114152;11279.266847654877;11292.209545331913;10741.78516429667;11072.446377681084;11095.334279943228;11098.890110988998;11103.72240693989;7902.762917112495;8178.877316840252;8267.214614611603;8271.54506502136;8276.917230827736;6027.156198132353;6288.308286088618;6315.052742088724;6337.144485494584;6342.39747224013;10271.794564108826;10339.948300258451;10376.118029967538;10400.323978941344;10403.271770975958;9508.85736713936;10139.442330671884;10271.897281027394;10620.309679870783;10626.8405973912;6286.073008020395;7867.370610351044;7871.409644276588;7873.921260787548;7878.724244651639;8706.042335343234;8863.202311791969;8870.467771933705;8898.199162075443;8900.465972041438;7840.411969102226;7966.960165199137;8042.638081286239;8050.637721302438;8232.094469608219;7870.488418252795;8018.3184429324165;8028.87956680662;8081.515109093473;8182;7198.5320954137815;7226.349628533457;7323.084614423197;7342.816429589303;7356.521826081287;5363.329583802025;5449.455054494678;5495.093309604031;5508.641938274;5624.409437009055;5421.647592906448;5489.917651235314;5497.257870187474;5502.477264659851;5548.223248745098 13:42:41 INFO - PROCESS | 29101 | |4;prototype.html;337.6741444506051;338.40609729923983;343.5019222127915;345.63362835394474;347.5152162732986;5355.357357117142;5364.597655175863;5370.301860758106;5370.677759334438;5385.892282154355;5211.557017653504;5224;5326.201069839526;5329.626926115174;5348.866278343044;3939.5469521005093;4004.6996475264327;4157.646600039;4165.895852603679;4190.120074784284;4121.690873184523;4147.5645057269;4150.522689890656;4161.542230354658;4164.5210800758;3952.664023558001;4105.958940410587;4132.834686612532;4134.896627584304;4135.710500264982;4053.4730485036903;4134.648554872854;4146.150039241978;4162.91674166515;4173.853915112956;3689.7786132832116;3698.3527882620433;3698.389765688658;3707.258548290326;3720.534933133358;5204.9219261711105;5227.137522308853;5227.163653815409;5227.8170264040955;5228.241904923765;4510.909781804346;4722.197226471508;4751.548602882721;4787.976060119712;4788.401449818773;5578.107502799573;5589.133684278943;5590.748416321235;5596.244506991545;5604.766951270714;3853.1523064925673;3993.0815912340327;4024.436578878959;4028.7784171870535;4059.269331520325;5628.409017053233;5668.263125793662;5699.031164702011;5735.8566035849435;5745.046684127375;4003.3394489908837;4008.4788977432745;4020.2763502569233;4020.632984785187;4045;3925.7448265862627;4092.5907409258834;4107.8973025674;4126.071633882412;4133.875983720493;4714.444233402969;4722.88192795176;4724.338592597039;4725.9527404726205;4726.527347265314;5482.369527504308;5486.506214440719;5594.888102238014;5602.38373778884;5603.831885043455;1338.712176881975;1393.8048673185763;1402.8176337076115;1411.2379315169694;1418.3121186224673;910.4400793511943;914.5472990869562;927.6335847340263;963.494165563085;992.2062350119875;5284.735763211824;5304.124819404832;5304.92042619361;5324.148136298174;5324.334458192726;5599.972000140055;5616.382197958222;5619.157126430986;5627.268455100811;5634.4365563443325;5114.616403769732;5275.417310242439;5280.574244953841;5315.574754019669;5318.680879147263;2946.852657367123;2951.3654564268786;2952.2029052156226;2966.7626589872757;2970.9554356684666;5665.3768085510565;5729.312482502127;5742.454466825645;5767.88464230713;5850.736716847752;5691.516221121251;5712.314522257273;5724.570657200727;5768.278965129359;5771.480566748929;5698.943010569924;5743.018658562789;5779.612892905672;5839.795607153729;5847.795327163529;5367.329083864517;5603.187537807076;5613.354464236665;5618.634788738719;5663.150527420755;5461.399246083007;5467.20725494809;5495.862603434946;5513.669179849061;5531.308586426697;5691;5736.913946290892;5772.94227057724;5788.536917046542;5795.7971471;3865.806709664562;4022.1352409232154;4049.210403971197;4060.898477538085;4061.8984525385918;5508.779648814003;5564.38791732901;5572.916406253828;5591.4688104629995;5603.655122770588;5329.173978033411;5491.450854914477;5494.890102197854;5502.972485137549;5504.669719816824;5385.3268341457315;5390.407055223844;5432.212329212163;5432.239486471976;5436.646617969819;5151.330327057459;5312.4156342803035;5346.251524786455;5350.250964864844;5362.705051222064;5479.917801232905;5487.862803429946;5521.972390138024;5527.39198688152;5545.7781688732;5354.063038968087;5381.757820898148;5384.684585628217;5392.272043274264;5419.376771671308 13:42:41 INFO - PROCESS | 29101 | |5;yui.html;753.6533194730424;757.114176413596;804.6218277409615;807.2129673568272;860.2730692564783;15443.305051272691;16172.919135404336;16562.66874662506;16592.751108733377;16602.75095873563;14120.435182592684;14226.502072427467;14234;14239.572812815606;14250;651.2087813306831;779.2207792207793;780.2275747010448;795.7055889320931;797.7447216890597;806.7418426103649;812.2080971053206;818.6234332207162;819.774561995453;820.1798201798201;851.7998270406449;854.3549620036863;855.486707975213;855.5978690015683;856.1139220906363;667.3426674725391;802.0695992648558;823.4894365493373;841.7558907916697;849.4987957105307;599.3856297295264;601.2244204975576;602.16299343912;603.6528995827373;604.2688347100014;15307.846921530754;15335.616609584738;15344.923275383664;15350.616234594112;15351.923240383838;13642.181469111885;13830.23933683647;13893.58319250424;13921.860781392157;14177;12181.208221465577;12291.877081229251;12311.384430778515;12337.830825116751;12454.501819927193;4417.109863228059;4466.173757854807;4538.72767633943;4615.630749540029;4739.241721324606;76432;76968.23031769667;77472.61263693645;78032.60983695101;78167.21832781656;9954.950225248827;10164.186865050779;10268.794624107475;10339.741506462247;10532.947335263429;19781.307654232034;19810.108545115505;19833;19843.60312793736;19849.801501985083;7716.917207324424;7918.168592297841;8032.357411407073;8052.194780522018;8157.55133467665;4805.110038215675;4809.591184749335;4827.61379089672;4887.413510378778;4924.901501969941;3464.445688689848;3602.3875941090078;3614.927701446009;3616.5479315085613;3622.710183185339;599.241958921964;601.9488343490852;608.9147519347295;609.107657282075;610.1884493623559;535.1170568561904;556.6632187526569;642.6786606696652;662.6090606542067;720.495653042872;8886.155815197675;9018.909810901807;9063.637454501877;9381.437113773196;9509.381890177116;630.2279707358449;686.6498085976189;690.6857379892137;692.5567636712508;725.2566119727321;4235.30117530604;4450.332450132506;4492.595666390041;4541.772911354486;4550.294704320836;66218.33781662122;68322.65838670774;68693.65653171702;68731.65634171898;115158;78246;93432;93670;93806.53096734473;94052;87876;88378;89112.10887891168;89228.5538572303;90830;9181.357304988587;9586.722716602086;9751.658691945748;9772.26707996889;9797.016263604664;50414;50754.49245507498;51974.22038669347;52236.477635224684;53714;54814.72592637011;64594.35405646071;65399.673001634685;65697.34302656913;67924.26416547138;13788.862111378758;13844.515441959484;13876.861231387556;14020.64948376258;14179.43282268739;14146.858531414555;14194.148351098967;14271.64320891986;14387.28063596837;14431.927840360731;13185.934070329587;13436.529721459701;13629.318534073454;13641.249731264876;13660.521881734092;13141.802872957105;13197.406116724962;13237.602871913858;13307.068505204543;13346.70054792342;13135;13256.204627722367;13293.601191964257;13386.732265354833;13494.865051349754;10559.788804224027;10583.259171857895;10677;10836.512356944115;10893.618723344645;9942.801143976936;10640.308379955279;11136.814769487792;11159.72100697489;11241.71895702614 13:42:41 INFO - PROCESS | 29101 | __end_tp_report 13:42:41 INFO - PROCESS | 29101 | __start_cc_report 13:42:41 INFO - PROCESS | 29101 | _x_x_mozilla_cycle_collect,1000 13:42:41 INFO - PROCESS | 29101 | __end_cc_report 13:42:41 INFO - PROCESS | 29101 | __startTimestamp1468960961429__endTimestamp 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | ------- Summary: start ------- 13:42:41 INFO - PROCESS | 29101 | Number of tests: 6 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | [#0] dojo.html Cycles:165 Average:5307.42 Median:5499.53 stddev:604.41 (11.0%) stddev-sans-first:579.20 13:42:41 INFO - PROCESS | 29101 | Values: 3027.6 3042.8 3087.4 3089.1 3101.6 5184.9 5236.9 5282.7 5401.1 5427.7 5221.3 5229.0 5291.0 5300.6 5302.4 4757.8 4794.6 4844.4 4855.6 5055.2 4957.0 4966.3 4967.9 5020.3 5028.4 4754.9 4820.2 4822.3 4873.6 4876.5 4782.4 4807.8 4814.6 4855.8 4906.8 4833.0 4848.3 4850.2 4852.8 4854.3 5186.3 5293.2 5341.1 5428.3 5430.2 5775.5 5781.1 5801.5 5826.6 5829.4 5586.6 5636.6 5849.5 5871.1 5871.3 5317.3 5384.0 5401.4 5408.6 5412.8 5913.2 5935.7 5938.2 5940.2 5945.9 5443.5 5472.9 5474.6 5629.3 5656.8 5657.7 5661.5 5661.7 5664.3 5664.8 5581.9 5600.1 5602.5 5605.5 5606.2 5518.2 5624.4 5627.9 5632.2 5634.1 4353.5 4380.0 4387.8 4423.3 4427.5 3883.7 3894.6 3895.7 3897.4 3902.6 5328.1 5391.2 5417.8 5455.6 5479.9 5736.3 5822.0 5825.4 5869.9 5876.5 5496.2 5499.7 5501.7 5507.5 5508.4 4837.4 4994.9 4996.0 5001.2 5030.5 5884.9 5925.7 5944.1 5964.8 5984.0 5797.2 5799.7 5800.2 5812.6 5813.8 5746.5 5768.6 5962.3 5987.3 5988.0 5528.3 5538.5 5575.7 5581.1 5634.7 5673.1 5676.0 5677.7 5678.3 5693.0 5490.9 5494.0 5519.9 5524.3 5558.2 5592.3 5595.9 5597.4 5598.1 5633.4 5532.1 5536.1 5536.5 5536.8 5542.6 5401.6 5499.5 5516.7 5552.9 5663.4 5428.4 5436.2 5437.9 5443.2 5444.5 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | [#1] ext.html Cycles:180 Average:61696.93 Median:43241.74 stddev:121029.26 (279.9%) stddev-sans-first:121281.83 13:42:41 INFO - PROCESS | 29101 | Values: 594.2 632.0 636.5 643.6 659.4 30234.2 43419.8 43989.8 44043.8 44067.6 46567.8 46871.8 46959.8 47078.0 47104.8 9049.7 9189.3 9190.6 9194.2 9200.0 9056.0 9144.0 9150.6 9162.5 9163.0 9199.2 9231.8 9234.3 9238.9 9246.0 9141.1 9166.3 9172.0 9185.4 9185.9 9018.2 9136.5 9141.1 9142.1 9144.2 43324.4 43459.3 43508.8 43582.1 43682.8 3425.9 3517.1 3545.3 3549.2 3646.2 33113.7 33191.7 33262.2 33570.7 34620.1 4358.0 4487.9 4496.4 4507.5 4536.5 363033.0 364777.0 366105.0 367309.0 367943.0 53132.7 60849.0 61783.4 61920.0 61980.0 61946.0 63225.7 63402.4 69842.0 69934.7 16215.4 16375.8 16464.0 16486.5 16503.3 11265.6 11504.4 11804.8 11834.9 11844.8 17893.0 17935.4 18395.9 18654.6 18913.4 6751.6 6776.8 6876.5 6958.9 7024.0 931.0 1009.9 1070.1 1147.3 1154.1 33035.3 34352.0 34362.8 34467.8 34643.0 1116.5 1118.2 1178.4 1206.7 1208.7 17676.9 17845.6 17947.2 18016.5 18110.8 570409.0 572476.0 572829.0 573756.0 574012.0 60718.4 60936.4 61002.4 61090.1 61106.7 1240.3 1343.1 1370.9 1402.4 1402.8 33435.8 34917.8 35016.3 35043.0 35258.5 52377.5 56368.9 57998.7 58100.0 64135.4 402429.0 402692.0 403151.0 403255.0 405989.0 25211.5 27934.0 28631.4 28895.3 29115.1 49395.5 50106.0 50272.2 50365.0 51206.0 41758.4 42585.0 42901.8 43198.4 43527.3 42196.4 42289.8 42357.4 42369.2 43285.1 39181.8 39365.8 40355.8 43511.0 43544.3 35752.5 37608.6 38076.4 38243.2 38912.0 28697.9 28925.0 29053.0 29053.4 29070.0 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | [#2] jquery.html Cycles:180 Average:30941.00 Median:2025.63 stddev:83160.09 (4105.4%) stddev-sans-first:83361.74 13:42:41 INFO - PROCESS | 29101 | Values: 392.9 393.2 395.0 400.5 422.2 13396.7 18964.5 20365.0 20480.4 20543.3 14646.3 19396.9 19438.0 19877.4 20336.1 5823.7 6003.2 6007.8 6020.8 6038.8 5946.3 6009.2 6011.7 6016.1 6019.1 4218.0 4731.7 6655.9 6662.7 6792.6 6485.6 6725.4 6736.7 6764.3 6770.1 5876.9 5900.9 5909.6 5913.4 5916.3 23816.8 24296.1 25096.1 25212.4 25295.6 2426.6 2726.8 3002.0 3121.1 3149.4 14939.9 14972.2 15088.5 15204.5 15500.6 1772.3 1869.3 1934.4 1988.7 2031.2 79746.2 82137.6 93221.9 103591.5 106218.9 16881.7 18518.9 18635.0 18760.9 18862.6 32553.8 32892.2 32903.3 33051.0 33088.7 9664.8 10063.5 10234.4 10278.1 10483.0 8425.8 8546.1 8632.8 8735.4 8740.0 7451.0 8670.8 9055.3 9135.0 9440.9 3486.7 3619.8 3643.6 3657.1 3658.3 960.7 979.7 1039.9 1048.9 1079.1 20661.8 21228.5 21266.5 21279.5 21370.0 981.0 987.8 1000.4 1010.3 1050.2 11280.5 11683.3 11767.9 11779.4 11782.8 496940.0 497197.0 506323.0 507014.0 510395.0 68139.0 101103.5 101359.0 101591.0 101952.0 978.8 1006.6 1015.6 1037.6 1039.8 18342.7 18375.4 18739.5 18854.4 18930.6 41750.0 42512.8 43001.8 44073.7 44123.8 57398.4 59070.4 59739.0 60246.0 60459.7 7650.4 7685.0 7737.8 8466.2 8717.7 9039.6 9279.4 9503.6 9634.0 10159.5 8276.6 8563.8 8605.0 8683.3 9077.0 8359.1 8622.1 8713.1 8756.7 9043.1 8946.9 8989.3 9076.5 9083.5 9108.4 13015.9 13414.0 13436.7 13540.9 13552.1 12543.1 12662.6 12883.4 12987.6 13080.5 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | [#3] mootools.html Cycles:180 Average:5917.60 Median:4773.27 stddev:3395.49 (71.1%) stddev-sans-first:3386.97 13:42:41 INFO - PROCESS | 29101 | Values: 1259.6 1435.6 1475.8 1579.6 1742.1 8379.0 8639.7 8651.3 8651.9 8959.7 8234.8 8484.9 8681.1 8682.7 8692.0 2010.2 2080.4 2085.7 2155.8 2203.8 2104.9 2106.2 2294.4 2354.1 2358.5 2285.3 2330.4 2347.4 2347.5 2348.4 1927.1 2216.5 2219.0 2220.0 2221.9 2184.5 2230.7 2234.8 2252.6 2262.7 8475.1 8510.3 8524.6 8530.0 8533.0 5111.3 5209.6 5211.4 5213.8 5218.2 3239.6 3336.5 3372.5 3394.2 3396.6 1090.9 1173.7 1184.0 2168.9 2187.2 12693.5 12952.9 12957.2 12970.2 12974.0 4749.2 4763.6 4782.9 4842.8 4925.4 10759.8 10761.2 10767.9 10795.0 10810.7 5444.8 5477.8 5503.8 5511.2 5551.9 5017.9 5067.8 5074.9 5112.6 5129.2 4032.4 4052.8 4105.3 4138.8 4150.4 1261.7 1280.9 1305.8 1307.4 1317.9 882.1 924.1 974.3 983.1 998.3 6185.8 6324.7 6459.1 6479.3 6515.0 428.9 430.8 431.9 450.7 453.1 4144.5 4453.1 4456.6 4489.5 4507.4 10821.4 11203.8 11277.4 11279.3 11292.2 10741.8 11072.4 11095.3 11098.9 11103.7 7902.8 8178.9 8267.2 8271.5 8276.9 6027.2 6288.3 6315.1 6337.1 6342.4 10271.8 10339.9 10376.1 10400.3 10403.3 9508.9 10139.4 10271.9 10620.3 10626.8 6286.1 7867.4 7871.4 7873.9 7878.7 8706.0 8863.2 8870.5 8898.2 8900.5 7840.4 7967.0 8042.6 8050.6 8232.1 7870.5 8018.3 8028.9 8081.5 8182.0 7198.5 7226.3 7323.1 7342.8 7356.5 5363.3 5449.5 5495.1 5508.6 5624.4 5421.6 5489.9 5497.3 5502.5 5548.2 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | [#4] prototype.html Cycles:180 Average:4638.71 Median:5329.40 stddev:1353.08 (25.4%) stddev-sans-first:1317.80 13:42:41 INFO - PROCESS | 29101 | Values: 337.7 338.4 343.5 345.6 347.5 5355.4 5364.6 5370.3 5370.7 5385.9 5211.6 5224.0 5326.2 5329.6 5348.9 3939.5 4004.7 4157.6 4165.9 4190.1 4121.7 4147.6 4150.5 4161.5 4164.5 3952.7 4106.0 4132.8 4134.9 4135.7 4053.5 4134.6 4146.2 4162.9 4173.9 3689.8 3698.4 3698.4 3707.3 3720.5 5204.9 5227.1 5227.2 5227.8 5228.2 4510.9 4722.2 4751.5 4788.0 4788.4 5578.1 5589.1 5590.7 5596.2 5604.8 3853.2 3993.1 4024.4 4028.8 4059.3 5628.4 5668.3 5699.0 5735.9 5745.0 4003.3 4008.5 4020.3 4020.6 4045.0 3925.7 4092.6 4107.9 4126.1 4133.9 4714.4 4722.9 4724.3 4726.0 4726.5 5482.4 5486.5 5594.9 5602.4 5603.8 1338.7 1393.8 1402.8 1411.2 1418.3 910.4 914.5 927.6 963.5 992.2 5284.7 5304.1 5304.9 5324.1 5324.3 5600.0 5616.4 5619.2 5627.3 5634.4 5114.6 5275.4 5280.6 5315.6 5318.7 2946.9 2951.4 2952.2 2966.8 2971.0 5665.4 5729.3 5742.5 5767.9 5850.7 5691.5 5712.3 5724.6 5768.3 5771.5 5698.9 5743.0 5779.6 5839.8 5847.8 5367.3 5603.2 5613.4 5618.6 5663.2 5461.4 5467.2 5495.9 5513.7 5531.3 5691.0 5736.9 5772.9 5788.5 5795.8 3865.8 4022.1 4049.2 4060.9 4061.9 5508.8 5564.4 5572.9 5591.5 5603.7 5329.2 5491.5 5494.9 5503.0 5504.7 5385.3 5390.4 5432.2 5432.2 5436.6 5151.3 5312.4 5346.3 5350.3 5362.7 5479.9 5487.9 5522.0 5527.4 5545.8 5354.1 5381.8 5384.7 5392.3 5419.4 13:42:41 INFO - PROCESS | 29101 | 13:42:41 INFO - PROCESS | 29101 | [#5] yui.html Cycles:180 Average:19243.48 Median:51364.36 stddev:26426.77 (51.4%) stddev-sans-first:26464.43 13:42:41 INFO - PROCESS | 29101 | Values: 753.7 757.1 804.6 807.2 860.3 15443.3 16172.9 16562.7 16592.8 16602.8 14120.4 14226.5 14234.0 14239.6 14250.0 651.2 779.2 780.2 795.7 797.7 806.7 812.2 818.6 819.8 820.2 851.8 854.4 855.5 855.6 856.1 667.3 802.1 823.5 841.8 849.5 599.4 601.2 602.2 603.7 604.3 15307.8 15335.6 15344.9 15350.6 15351.9 13642.2 13830.2 13893.6 13921.9 14177.0 12181.2 12291.9 12311.4 12337.8 12454.5 4417.1 4466.2 4538.7 4615.6 4739.2 76432.0 76968.2 77472.6 78032.6 78167.2 9955.0 10164.2 10268.8 10339.7 10532.9 19781.3 19810.1 19833.0 19843.6 19849.8 7716.9 7918.2 8032.4 8052.2 8157.6 4805.1 4809.6 4827.6 4887.4 4924.9 3464.4 3602.4 3614.9 3616.5 3622.7 599.2 601.9 608.9 609.1 610.2 535.1 556.7 642.7 662.6 720.5 8886.2 9018.9 9063.6 9381.4 9509.4 630.2 686.6 690.7 692.6 725.3 4235.3 4450.3 4492.6 4541.8 4550.3 66218.3 68322.7 68693.7 68731.7 115158.0 78246.0 93432.0 93670.0 93806.5 94052.0 87876.0 88378.0 89112.1 89228.6 90830.0 9181.4 9586.7 9751.7 9772.3 9797.0 50414.0 50754.5 51974.2 52236.5 53714.0 54814.7 64594.4 65399.7 65697.3 67924.3 13788.9 13844.5 13876.9 14020.6 14179.4 14146.9 14194.1 14271.6 14387.3 14431.9 13185.9 13436.5 13629.3 13641.2 13660.5 13141.8 13197.4 13237.6 13307.1 13346.7 13135.0 13256.2 13293.6 13386.7 13494.9 10559.8 10583.3 10677.0 10836.5 10893.6 9942.8 10640.3 11136.8 11159.7 11241.7 13:42:41 INFO - PROCESS | 29101 | -------- Summary: end -------- 13:42:41 INFO - PROCESS | 29101 | 13:42:42 INFO - TEST-INFO | 29101: exit 0 13:42:42 INFO - TEST-OK | dromaeo_css | took 1112902ms 13:42:42 INFO - TEST-START | kraken 13:42:42 INFO - operating with platform_type : linux_ 13:42:42 INFO - Initialising browser for kraken test... 13:42:42 INFO - TEST-INFO | started process 29285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCa7RlZ/profile http://localhost:46809/getInfo.html) 13:42:45 INFO - PROCESS | 29285 | __metrics Screen width/height:1600/1200 13:42:45 INFO - PROCESS | 29285 | colorDepth:24 13:42:45 INFO - PROCESS | 29285 | Browser inner width/height: 1024/697 13:42:45 INFO - PROCESS | 29285 | __metrics 13:42:46 INFO - PROCESS | 29285 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:42:46 INFO - PROCESS | 29285 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:42:47 INFO - TEST-INFO | 29285: exit 0 13:42:47 INFO - Browser initialized. 13:42:47 INFO - Running cycle 1/1 for kraken test... 13:42:47 INFO - TEST-INFO | started process 29366 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCa7RlZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:42:59 INFO - PROCESS | 29366 | RSS: Main: 157454336 13:42:59 INFO - PROCESS | 29366 | 13:43:01 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-beat-detection) 13:43:02 INFO - PROCESS | 29366 | RSS: Main: 217296896 13:43:02 INFO - PROCESS | 29366 | 13:43:05 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-dft) 13:43:06 INFO - PROCESS | 29366 | RSS: Main: 206127104 13:43:06 INFO - PROCESS | 29366 | 13:43:09 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-fft) 13:43:09 INFO - PROCESS | 29366 | RSS: Main: 269578240 13:43:09 INFO - PROCESS | 29366 | 13:43:11 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-oscillator) 13:43:12 INFO - PROCESS | 29366 | RSS: Main: 180281344 13:43:12 INFO - PROCESS | 29366 | 13:43:14 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46809/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:43:15 INFO - PROCESS | 29366 | RSS: Main: 185237504 13:43:15 INFO - PROCESS | 29366 | 13:43:18 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46809/tests/kraken/driver.html?testName=imaging-darkroom) 13:43:18 INFO - PROCESS | 29366 | RSS: Main: 219324416 13:43:18 INFO - PROCESS | 29366 | 13:43:22 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46809/tests/kraken/driver.html?testName=imaging-desaturate) 13:43:22 INFO - PROCESS | 29366 | RSS: Main: 221003776 13:43:22 INFO - PROCESS | 29366 | 13:43:25 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46809/tests/kraken/driver.html?testName=json-parse-financial) 13:43:26 INFO - PROCESS | 29366 | RSS: Main: 221106176 13:43:26 INFO - PROCESS | 29366 | 13:43:27 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46809/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:43:28 INFO - PROCESS | 29366 | RSS: Main: 209616896 13:43:28 INFO - PROCESS | 29366 | 13:43:30 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:43:30 INFO - PROCESS | 29366 | RSS: Main: 214073344 13:43:30 INFO - PROCESS | 29366 | 13:43:32 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:43:33 INFO - PROCESS | 29366 | RSS: Main: 213901312 13:43:33 INFO - PROCESS | 29366 | 13:43:35 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:43:36 INFO - PROCESS | 29366 | RSS: Main: 224223232 13:43:36 INFO - PROCESS | 29366 | 13:43:39 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:43:39 INFO - PROCESS | 29366 | RSS: Main: 225595392 13:43:39 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46809/tests/kraken/driver.html?testName=ai-astar) 13:43:41 INFO - PROCESS | 29366 | RSS: Main: 267460608 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | __start_tp_report 13:43:41 INFO - PROCESS | 29366 | _x_x_mozilla_page_load 13:43:41 INFO - PROCESS | 29366 | _x_x_mozilla_page_load_details 13:43:41 INFO - PROCESS | 29366 | |i|pagename|runs| 13:43:41 INFO - PROCESS | 29366 | |0;ai-astar;101;111;110;110;110;112;112;110;109;112 13:43:41 INFO - PROCESS | 29366 | |1;audio-beat-detection;152;115;117;169;117;118;142;120;161;114 13:43:41 INFO - PROCESS | 29366 | |2;audio-dft;161;160;161;160;163;161;161;160;160;160 13:43:41 INFO - PROCESS | 29366 | |3;audio-fft;75;76;75;106;75;76;74;74;102;75 13:43:41 INFO - PROCESS | 29366 | |4;audio-oscillator;83;84;112;84;84;84;117;84;84;128 13:43:41 INFO - PROCESS | 29366 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;116;117;117 13:43:41 INFO - PROCESS | 29366 | |6;imaging-darkroom;146;146;147;145;145;148;148;147;146;146 13:43:41 INFO - PROCESS | 29366 | |7;imaging-desaturate;95;96;95;96;95;95;94;95;96;95 13:43:41 INFO - PROCESS | 29366 | |8;json-parse-financial;51;51;50;50;50;50;50;50;51;49 13:43:41 INFO - PROCESS | 29366 | |9;json-stringify-tinderbox;62;63;63;63;63;62;63;62;61;63 13:43:41 INFO - PROCESS | 29366 | |10;stanford-crypto-aes;76;83;76;75;83;75;83;77;76;75 13:43:41 INFO - PROCESS | 29366 | |11;stanford-crypto-ccm;129;119;118;128;117;121;122;120;170;121 13:43:41 INFO - PROCESS | 29366 | |12;stanford-crypto-pbkdf2;154;157;155;155;155;161;157;160;156;157 13:43:41 INFO - PROCESS | 29366 | |13;stanford-crypto-sha256-iterative;63;67;62;66;64;61;61;61;65;62 13:43:41 INFO - PROCESS | 29366 | __end_tp_report 13:43:41 INFO - PROCESS | 29366 | __start_cc_report 13:43:41 INFO - PROCESS | 29366 | _x_x_mozilla_cycle_collect,1649 13:43:41 INFO - PROCESS | 29366 | __end_cc_report 13:43:41 INFO - PROCESS | 29366 | __startTimestamp1468961021545__endTimestamp 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | ------- Summary: start ------- 13:43:41 INFO - PROCESS | 29366 | Number of tests: 14 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:3.23 (2.9%) stddev-sans-first:1.12 13:43:41 INFO - PROCESS | 29366 | Values: 101.0 111.0 110.0 110.0 110.0 112.0 112.0 110.0 109.0 112.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:131.00 stddev:21.37 (16.3%) stddev-sans-first:21.47 13:43:41 INFO - PROCESS | 29366 | Values: 152.0 115.0 117.0 169.0 117.0 118.0 142.0 120.0 161.0 114.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 13:43:41 INFO - PROCESS | 29366 | Values: 161.0 160.0 161.0 160.0 163.0 161.0 161.0 160.0 160.0 160.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.28 (16.4%) stddev-sans-first:12.85 13:43:41 INFO - PROCESS | 29366 | Values: 75.0 76.0 75.0 106.0 75.0 76.0 74.0 74.0 102.0 75.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:84.00 stddev:17.41 (20.7%) stddev-sans-first:17.97 13:43:41 INFO - PROCESS | 29366 | Values: 83.0 84.0 112.0 84.0 84.0 84.0 117.0 84.0 84.0 128.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 13:43:41 INFO - PROCESS | 29366 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:1.07 (0.7%) stddev-sans-first:1.13 13:43:41 INFO - PROCESS | 29366 | Values: 146.0 146.0 147.0 145.0 145.0 148.0 148.0 147.0 146.0 146.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 13:43:41 INFO - PROCESS | 29366 | Values: 95.0 96.0 95.0 96.0 95.0 95.0 94.0 95.0 96.0 95.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:0.63 (1.3%) stddev-sans-first:0.60 13:43:41 INFO - PROCESS | 29366 | Values: 51.0 51.0 50.0 50.0 50.0 50.0 50.0 50.0 51.0 49.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:63.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 13:43:41 INFO - PROCESS | 29366 | Values: 62.0 63.0 63.0 63.0 63.0 62.0 63.0 62.0 61.0 63.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:76.50 stddev:3.57 (4.7%) stddev-sans-first:3.72 13:43:41 INFO - PROCESS | 29366 | Values: 76.0 83.0 76.0 75.0 83.0 75.0 83.0 77.0 76.0 75.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:121.50 stddev:15.79 (13.0%) stddev-sans-first:16.72 13:43:41 INFO - PROCESS | 29366 | Values: 129.0 119.0 118.0 128.0 117.0 121.0 122.0 120.0 170.0 121.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:157.00 stddev:2.26 (1.4%) stddev-sans-first:2.18 13:43:41 INFO - PROCESS | 29366 | Values: 154.0 157.0 155.0 155.0 155.0 161.0 157.0 160.0 156.0 157.0 13:43:41 INFO - PROCESS | 29366 | 13:43:41 INFO - PROCESS | 29366 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:2.20 (3.5%) stddev-sans-first:2.33 13:43:41 INFO - PROCESS | 29366 | Values: 63.0 67.0 62.0 66.0 64.0 61.0 61.0 61.0 65.0 62.0 13:43:41 INFO - PROCESS | 29366 | -------- Summary: end -------- 13:43:41 INFO - PROCESS | 29366 | 13:43:42 INFO - TEST-INFO | 29366: exit 0 13:43:42 INFO - TEST-OK | kraken | took 60462ms 13:43:42 INFO - SUITE-END | took 1173s 13:43:42 INFO - Completed test suite (00:19:33) 13:43:42 INFO - javascript benchmark 13:43:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.6064111665487, 3042.771792115592, 3087.4442600331945, 3089.0887188279457, 3101.5967924169854, 5184.922226166601, 5236.92144617831, 5282.6830390176565, 5401.081816091264, 5427.701476418801, 5221.347331583552, 5229.032628963635, 5290.973545132269, 5300.575953923687, 5302.416734159239, 4757.833475828338, 4794.616430685554, 4844.418669759634, 4855.5872750816225, 5055.216441451581, 4956.950430495703, 4966.25506174075, 4967.850964471072, 5020.347354843865, 5028.371453568304, 4754.881127971811, 4820.228763397857, 4822.300766388889, 4873.585745211662, 4876.512348765131, 4782.402199725035, 4807.78364973577, 4814.638902082323, 4855.781489832966, 4906.803727850882, 4832.951670483285, 4848.345473361086, 4850.199717046683, 4852.805887764521, 4854.2718592211095, 5186.299849520305, 5293.179557168645, 5341.092014357569, 5428.294321738188, 5430.239766432703, 5775.537956963432, 5781.075027995501, 5801.53587712982, 5826.621269617462, 5829.417058294179, 5586.608937374385, 5636.633618814764, 5849.473547380713, 5871.089981052944, 5871.324797648283, 5317.282166907496, 5383.973080134574, 5401.378841433247, 5408.5673146149, 5412.756425960802, 5913.201717768132, 5935.73289201987, 5938.168656388109, 5940.198073260141, 5945.881082378328, 5443.537299329601, 5472.945270547243, 5474.589405794582, 5629.2681951346885, 5656.802011929645, 5657.688827114548, 5661.46216109469, 5661.66030038199, 5664.320281566156, 5664.801731939445, 5581.916271255935, 5600.075987462104, 5602.4957753802355, 5605.523530499871, 5606.187102870061, 5518.227448157261, 5624.437556244425, 5627.8593035174445, 5632.183333416631, 5634.070378387602, 4353.455818022747, 4379.978100109479, 4387.802548885308, 4423.336499525032, 4427.490838553543, 3883.728139030241, 3894.571597124314, 3895.7467764595776, 3897.434871943609, 3902.570717221103, 5328.147496400557, 5391.191321301836, 5417.83746487606, 5455.563554915597, 5479.890402191933, 5736.282964629421, 5822.0, 5825.446582574648, 5869.911951320733, 5876.529877609781, 5496.230527726121, 5499.7250137492965, 5501.669899806025, 5507.476789704971, 5508.366537848118, 4837.419509658864, 4994.875128121826, 4995.9500404996215, 5001.24981252815, 5030.4718004608985, 5884.852878677982, 5925.674087925206, 5944.138099975566, 5964.821055368346, 5984.012637914782, 5797.2173756542325, 5799.710014499343, 5800.216970708901, 5812.593118481665, 5813.79651712188, 5746.5402767779315, 5768.625039372428, 5962.25471816023, 5987.251593550806, 5987.970060149672, 5528.336599608073, 5538.501534861882, 5575.721213939368, 5581.051221292471, 5634.74643641029, 5673.120666296731, 5675.971620142038, 5677.74450149736, 5678.290213723285, 5692.971535142297, 5490.890182196254, 5494.038543254995, 5519.862003449946, 5524.337079550319, 5558.249636299048, 5592.27300450955, 5595.8880822384135, 5597.356304024926, 5598.10430331145, 5633.352164501135, 5532.059549876451, 5536.086545719912, 5536.501714845684, 5536.778528858962, 5542.584306177134, 5401.6218864679095, 5499.532539734168, 5516.696581687886, 5552.888942221052, 5663.3770285267765, 5428.402875683751, 5436.157395603688, 5437.945620543744, 5443.210734443563, 5444.50999410055], "value": 5266.412818770414, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.1592646405337, 631.981040568783, 636.5480508838724, 643.5913195121096, 659.396652063362, 30234.244143896412, 43419.78290108549, 43989.7800510997, 44043.77978110105, 44067.55932440675, 46567.76716116415, 46871.765641171834, 46959.76520117403, 47078.0, 47104.764476177654, 9049.728508144766, 9189.264858811279, 9190.632374705037, 9194.172524472797, 9200.0, 9056.0, 9143.954280228589, 9150.588223529958, 9162.541872906362, 9163.037881022497, 9199.172074513292, 9231.815363692722, 9234.307426943005, 9238.86141707875, 9246.0, 9141.131592498736, 9166.26669866409, 9171.954140229256, 9185.40294880834, 9185.908140918606, 9018.188363047291, 9136.543172841331, 9141.131592498701, 9142.085791420872, 9144.222741067017, 43324.350134748005, 43459.34810977838, 43508.782456087836, 43582.128357432994, 43682.78158609186, 3425.880094196716, 3517.1383011162175, 3545.2732189901008, 3549.219171782204, 3646.179609587848, 33113.6688633113, 33191.66808331934, 33262.168445788804, 33570.664293357, 34620.13449663753, 4357.956420435787, 4487.932681009787, 4496.3705081288645, 4507.481639611454, 4536.455625324949, 363033.0, 364777.0, 366105.0, 367309.0, 367943.0, 53132.734336328074, 60849.0, 61783.382166178664, 61920.0, 61980.0, 61946.0, 63225.72904115994, 63402.36597634057, 69842.0, 69934.65032674804, 16215.35138594443, 16375.83624163767, 16464.0, 16486.505404837873, 16503.257353419125, 11265.605703800327, 11504.367259800623, 11804.822927656092, 11834.940825295818, 11844.822327665092, 17893.0, 17935.37226197077, 18395.90802046008, 18654.626907461774, 18913.43259702211, 6751.594904305757, 6776.76281330161, 6876.518643694993, 6958.930410695828, 7024.0, 930.9534523273809, 1009.8990100989843, 1070.1255779922528, 1147.311613032174, 1154.1228666213738, 33035.339293214, 34352.0, 34362.82818585941, 34467.827660861534, 34643.0, 1116.4529380603446, 1118.2395970739974, 1178.393127539318, 1206.7405507815686, 1208.6917835951776, 17676.91161544184, 17845.643087138185, 17947.192376343097, 18016.459506214833, 18110.818891811177, 570409.0, 572476.0, 572829.0, 573756.0, 574012.0, 60718.392816072155, 60936.39063609396, 61002.38997610056, 61090.083648745305, 61106.694466527384, 1240.3302216803006, 1343.147101590497, 1370.9108907920954, 1402.3689339797131, 1402.7825687018324, 33435.83282083574, 34917.825410872785, 35016.299674006885, 35043.0, 35258.47112293369, 52377.47622523726, 56368.87262254814, 57998.7100064497, 58100.0, 64135.35864641294, 402429.0, 402692.0, 403151.0, 403255.0, 405989.0, 25211.49577008486, 27934.0, 28631.421072365953, 28895.277618058877, 29115.126546203646, 49395.50604493909, 50106.0, 50272.245916312015, 50365.0, 51206.0, 41758.37362439626, 42585.0, 42901.78549107235, 43198.35202472028, 43527.34708979431, 42196.367054493596, 42289.78855105705, 42357.36463952981, 42369.15261694811, 43285.13429731325, 39181.80409097936, 39365.80317098396, 40355.79822100871, 43511.0, 43544.34683479814, 35752.463713043806, 37608.623913760515, 38076.42885356777, 38243.23513529769, 38912.0, 28697.856510717313, 28925.0, 29053.0, 29053.418931621673, 29070.0], "value": 20637.13920383839, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.9096307849193, 393.16060211448547, 394.9940750888736, 400.5353789604059, 422.1662217121186, 13396.67733107195, 18964.525886852836, 20365.0, 20480.385588432335, 20543.280985165522, 14646.34091465884, 19396.933168675718, 19438.0, 19877.403677889688, 20336.084876180572, 5823.67969761663, 6003.159557661931, 6007.789727359544, 6020.759169633209, 6038.849028774272, 5946.286445626531, 6009.248843894513, 6011.729472173763, 6016.0975853622085, 6019.1272265521475, 4217.957820421802, 4731.739754313511, 6655.900161497581, 6662.733490660392, 6792.558483698544, 6485.6108633482145, 6725.39471447572, 6736.730530778763, 6764.289749576297, 6770.119884415019, 5876.882462350772, 5900.911486327708, 5909.556783241274, 5913.408659134096, 5916.349201587822, 23816.761832381802, 24296.1496347627, 25096.12163574266, 25212.369690757696, 25295.62056569153, 2426.6360045993065, 2726.754592086702, 3002.0, 3121.094882484077, 3149.3543823516125, 14939.925300373428, 14972.17653029083, 15088.471903483434, 15204.543863684106, 15500.612484687861, 1772.2556526259002, 1869.3457289948544, 1934.429343343717, 1988.741463609736, 2031.177373163871, 79746.20253797446, 82137.58931205365, 93221.94057386316, 103591.4820425893, 106218.93781062168, 16881.662366752596, 18518.907405462887, 18635.0, 18760.906195468935, 18862.62274754497, 32553.83723081417, 32892.1776955578, 32903.3419331612, 33051.0, 33088.66911330856, 9664.806703865883, 10063.496825158712, 10234.437105959094, 10278.074973252444, 10483.0, 8425.789355266168, 8546.102659220816, 8632.827343453097, 8735.43219690718, 8739.956300218459, 7450.970594848455, 8670.826583468295, 9055.275577953747, 9134.954325228331, 9440.90559094414, 3486.7036301914113, 3619.7647152934974, 3643.635636436335, 3657.0767327720278, 3658.34149853029, 960.6973803251954, 979.6913972098766, 1039.880413752413, 1048.85840411545, 1079.09895237476, 20661.793382066287, 21228.469288267916, 21266.468338291666, 21279.468013299793, 21370.02166555185, 980.9950950245346, 987.8024395121005, 1000.3847633705219, 1010.2978429991085, 1050.222835102019, 11280.548778048951, 11683.299002059903, 11767.882051205122, 11779.411029448493, 11782.823257651142, 496940.0, 497197.0, 506323.0, 507014.0, 510395.0, 68139.0, 101103.4944825286, 101359.0, 101591.0, 101952.0, 978.8433850583872, 1006.605850896499, 1015.6242190389455, 1037.6160820496464, 1039.8284283093205, 18342.72485912739, 18375.448736537925, 18739.53151171177, 18854.389189726593, 18930.621387571897, 41750.0, 42512.78743606262, 43001.78499107484, 44073.67778966636, 44123.77938110289, 57398.42601573931, 59070.40929590821, 59739.0, 60246.0, 60459.69770151121, 7650.387968962358, 7684.961575192088, 7737.845243095219, 8466.153384661484, 8717.738467845975, 9039.63841446361, 9279.443233406017, 9503.572339244889, 9634.0, 10159.542820572944, 8276.627551760306, 8563.785905352415, 8604.956975215084, 8683.305335573265, 9077.046910074347, 8359.122292159236, 8622.137786221328, 8713.085126061673, 8756.693515726918, 9043.050479699537, 8946.86579701318, 8989.325800565115, 9076.455412675261, 9083.45499270046, 9108.407953483002, 13015.934920325337, 13413.99395045395, 13436.664083397602, 13540.86459135396, 13552.119112257671, 12543.12198146121, 12662.556810511587, 12883.42024608876, 12987.610371688865, 13080.542181023618], "value": 10190.35077324247, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1259.6410023143405, 1435.5549779568332, 1475.8376578576356, 1579.5656194546498, 1742.128935532233, 8379.0, 8639.65441382344, 8651.30789536837, 8651.870221946669, 8959.731208063753, 8234.794130146749, 8484.91515084849, 8681.131886811332, 8682.696105636303, 8691.956540217307, 2010.1858747207405, 2080.3758872338312, 2085.708000879878, 2155.8059774620283, 2203.823694104468, 2104.8947552622385, 2106.220698341608, 2294.403455101677, 2354.1407386303963, 2358.492924021335, 2285.257291380308, 2330.382448651109, 2347.3779448446176, 2347.5187586544716, 2348.424635964191, 1927.0750039980887, 2216.4791274050585, 2218.9778102219093, 2220.0342850859765, 2221.855579387343, 2184.4757258258064, 2230.654248591471, 2234.821214302868, 2252.6057939860457, 2262.7058482397183, 8475.067742548315, 8510.319174466027, 8524.57377131141, 8529.957350213272, 8532.957335213285, 5111.284420181178, 5209.609279304067, 5211.426743058299, 5213.843584692428, 5218.243354713583, 3239.5626590410466, 3336.4661654135466, 3372.5278461015478, 3394.1514621344663, 3396.6053931978145, 1090.8963648453384, 1173.683105561502, 1183.9704007399798, 2168.913243470259, 2187.1798075721604, 12693.492260309577, 12952.935235323952, 12957.157784743962, 12970.156939798977, 12974.026947978939, 4749.201689839126, 4763.642726795504, 4782.904341913142, 4842.782074806642, 4925.359703238556, 10759.83860242097, 10761.246712730033, 10767.89232107669, 10795.028447439769, 10810.729731756768, 5444.7822087116865, 5477.808276710296, 5503.807366742145, 5511.200875872976, 5551.916721249185, 5017.874553136201, 5067.771950262173, 5074.923876141861, 5112.590992720573, 5129.204973229081, 4032.4152997815154, 4052.8378864845668, 4105.261053010427, 4138.813753381105, 4150.3566947123245, 1261.678272040642, 1280.929548874802, 1305.8171855940177, 1307.3593938969836, 1317.8748018938186, 882.139913584263, 924.1128516623982, 974.2887691984749, 983.0759086458706, 998.2612866478898, 6185.752569897154, 6324.683765811691, 6459.128017717642, 6479.262075735933, 6514.967425162844, 428.88205743420184, 430.79106633282834, 431.8747563206635, 450.7115446114489, 453.1005953182929, 4144.48193975753, 4453.131639330364, 4456.64346852251, 4489.506154323022, 4507.4365704286965, 10821.404822734825, 11203.831942520868, 11277.379744114152, 11279.266847654877, 11292.209545331913, 10741.78516429667, 11072.446377681084, 11095.334279943228, 11098.890110988998, 11103.72240693989, 7902.762917112495, 8178.877316840252, 8267.214614611603, 8271.54506502136, 8276.917230827736, 6027.156198132353, 6288.308286088618, 6315.052742088724, 6337.144485494584, 6342.39747224013, 10271.794564108826, 10339.948300258451, 10376.118029967538, 10400.323978941344, 10403.271770975958, 9508.85736713936, 10139.442330671884, 10271.897281027394, 10620.309679870783, 10626.8405973912, 6286.073008020395, 7867.370610351044, 7871.409644276588, 7873.921260787548, 7878.724244651639, 8706.042335343234, 8863.202311791969, 8870.467771933705, 8898.199162075443, 8900.465972041438, 7840.411969102226, 7966.960165199137, 8042.638081286239, 8050.637721302438, 8232.094469608219, 7870.488418252795, 8018.3184429324165, 8028.87956680662, 8081.515109093473, 8182.0, 7198.5320954137815, 7226.349628533457, 7323.084614423197, 7342.816429589303, 7356.521826081287, 5363.329583802025, 5449.455054494678, 5495.093309604031, 5508.641938274, 5624.409437009055, 5421.647592906448, 5489.917651235314, 5497.257870187474, 5502.477264659851, 5548.223248745098], "value": 4643.4506429976045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.6741444506051, 338.40609729923983, 343.5019222127915, 345.63362835394474, 347.5152162732986, 5355.357357117142, 5364.597655175863, 5370.301860758106, 5370.677759334438, 5385.892282154355, 5211.557017653504, 5224.0, 5326.201069839526, 5329.626926115174, 5348.866278343044, 3939.5469521005093, 4004.6996475264327, 4157.646600039, 4165.895852603679, 4190.120074784284, 4121.690873184523, 4147.5645057269, 4150.522689890656, 4161.542230354658, 4164.5210800758, 3952.664023558001, 4105.958940410587, 4132.834686612532, 4134.896627584304, 4135.710500264982, 4053.4730485036903, 4134.648554872854, 4146.150039241978, 4162.91674166515, 4173.853915112956, 3689.7786132832116, 3698.3527882620433, 3698.389765688658, 3707.258548290326, 3720.534933133358, 5204.9219261711105, 5227.137522308853, 5227.163653815409, 5227.8170264040955, 5228.241904923765, 4510.909781804346, 4722.197226471508, 4751.548602882721, 4787.976060119712, 4788.401449818773, 5578.107502799573, 5589.133684278943, 5590.748416321235, 5596.244506991545, 5604.766951270714, 3853.1523064925673, 3993.0815912340327, 4024.436578878959, 4028.7784171870535, 4059.269331520325, 5628.409017053233, 5668.263125793662, 5699.031164702011, 5735.8566035849435, 5745.046684127375, 4003.3394489908837, 4008.4788977432745, 4020.2763502569233, 4020.632984785187, 4045.0, 3925.7448265862627, 4092.5907409258834, 4107.8973025674, 4126.071633882412, 4133.875983720493, 4714.444233402969, 4722.88192795176, 4724.338592597039, 4725.9527404726205, 4726.527347265314, 5482.369527504308, 5486.506214440719, 5594.888102238014, 5602.38373778884, 5603.831885043455, 1338.712176881975, 1393.8048673185763, 1402.8176337076115, 1411.2379315169694, 1418.3121186224673, 910.4400793511943, 914.5472990869562, 927.6335847340263, 963.494165563085, 992.2062350119875, 5284.735763211824, 5304.124819404832, 5304.92042619361, 5324.148136298174, 5324.334458192726, 5599.972000140055, 5616.382197958222, 5619.157126430986, 5627.268455100811, 5634.4365563443325, 5114.616403769732, 5275.417310242439, 5280.574244953841, 5315.574754019669, 5318.680879147263, 2946.852657367123, 2951.3654564268786, 2952.2029052156226, 2966.7626589872757, 2970.9554356684666, 5665.3768085510565, 5729.312482502127, 5742.454466825645, 5767.88464230713, 5850.736716847752, 5691.516221121251, 5712.314522257273, 5724.570657200727, 5768.278965129359, 5771.480566748929, 5698.943010569924, 5743.018658562789, 5779.612892905672, 5839.795607153729, 5847.795327163529, 5367.329083864517, 5603.187537807076, 5613.354464236665, 5618.634788738719, 5663.150527420755, 5461.399246083007, 5467.20725494809, 5495.862603434946, 5513.669179849061, 5531.308586426697, 5691.0, 5736.913946290892, 5772.94227057724, 5788.536917046542, 5795.7971471, 3865.806709664562, 4022.1352409232154, 4049.210403971197, 4060.898477538085, 4061.8984525385918, 5508.779648814003, 5564.38791732901, 5572.916406253828, 5591.4688104629995, 5603.655122770588, 5329.173978033411, 5491.450854914477, 5494.890102197854, 5502.972485137549, 5504.669719816824, 5385.3268341457315, 5390.407055223844, 5432.212329212163, 5432.239486471976, 5436.646617969819, 5151.330327057459, 5312.4156342803035, 5346.251524786455, 5350.250964864844, 5362.705051222064, 5479.917801232905, 5487.862803429946, 5521.972390138024, 5527.39198688152, 5545.7781688732, 5354.063038968087, 5381.757820898148, 5384.684585628217, 5392.272043274264, 5419.376771671308], "value": 4209.750861971504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.6533194730424, 757.114176413596, 804.6218277409615, 807.2129673568272, 860.2730692564783, 15443.305051272691, 16172.919135404336, 16562.66874662506, 16592.751108733377, 16602.75095873563, 14120.435182592684, 14226.502072427467, 14234.0, 14239.572812815606, 14250.0, 651.2087813306831, 779.2207792207793, 780.2275747010448, 795.7055889320931, 797.7447216890597, 806.7418426103649, 812.2080971053206, 818.6234332207162, 819.774561995453, 820.1798201798201, 851.7998270406449, 854.3549620036863, 855.486707975213, 855.5978690015683, 856.1139220906363, 667.3426674725391, 802.0695992648558, 823.4894365493373, 841.7558907916697, 849.4987957105307, 599.3856297295264, 601.2244204975576, 602.16299343912, 603.6528995827373, 604.2688347100014, 15307.846921530754, 15335.616609584738, 15344.923275383664, 15350.616234594112, 15351.923240383838, 13642.181469111885, 13830.23933683647, 13893.58319250424, 13921.860781392157, 14177.0, 12181.208221465577, 12291.877081229251, 12311.384430778515, 12337.830825116751, 12454.501819927193, 4417.109863228059, 4466.173757854807, 4538.72767633943, 4615.630749540029, 4739.241721324606, 76432.0, 76968.23031769667, 77472.61263693645, 78032.60983695101, 78167.21832781656, 9954.950225248827, 10164.186865050779, 10268.794624107475, 10339.741506462247, 10532.947335263429, 19781.307654232034, 19810.108545115505, 19833.0, 19843.60312793736, 19849.801501985083, 7716.917207324424, 7918.168592297841, 8032.357411407073, 8052.194780522018, 8157.55133467665, 4805.110038215675, 4809.591184749335, 4827.61379089672, 4887.413510378778, 4924.901501969941, 3464.445688689848, 3602.3875941090078, 3614.927701446009, 3616.5479315085613, 3622.710183185339, 599.241958921964, 601.9488343490852, 608.9147519347295, 609.107657282075, 610.1884493623559, 535.1170568561904, 556.6632187526569, 642.6786606696652, 662.6090606542067, 720.495653042872, 8886.155815197675, 9018.909810901807, 9063.637454501877, 9381.437113773196, 9509.381890177116, 630.2279707358449, 686.6498085976189, 690.6857379892137, 692.5567636712508, 725.2566119727321, 4235.30117530604, 4450.332450132506, 4492.595666390041, 4541.772911354486, 4550.294704320836, 66218.33781662122, 68322.65838670774, 68693.65653171702, 68731.65634171898, 115158.0, 78246.0, 93432.0, 93670.0, 93806.53096734473, 94052.0, 87876.0, 88378.0, 89112.10887891168, 89228.5538572303, 90830.0, 9181.357304988587, 9586.722716602086, 9751.658691945748, 9772.26707996889, 9797.016263604664, 50414.0, 50754.49245507498, 51974.22038669347, 52236.477635224684, 53714.0, 54814.72592637011, 64594.35405646071, 65399.673001634685, 65697.34302656913, 67924.26416547138, 13788.862111378758, 13844.515441959484, 13876.861231387556, 14020.64948376258, 14179.43282268739, 14146.858531414555, 14194.148351098967, 14271.64320891986, 14387.28063596837, 14431.927840360731, 13185.934070329587, 13436.529721459701, 13629.318534073454, 13641.249731264876, 13660.521881734092, 13141.802872957105, 13197.406116724962, 13237.602871913858, 13307.068505204543, 13346.70054792342, 13135.0, 13256.204627722367, 13293.601191964257, 13386.732265354833, 13494.865051349754, 10559.788804224027, 10583.259171857895, 10677.0, 10836.512356944115, 10893.618723344645, 9942.801143976936, 10640.308379955279, 11136.814769487792, 11159.72100697489, 11241.71895702614], "value": 7334.827937156036, "unit": "score"}], "name": "dromaeo_css", "value": 7358.931624564508, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 110.0, 110.0, 110.0, 112.0, 112.0, 110.0, 109.0, 112.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 117.0, 169.0, 117.0, 118.0, 142.0, 120.0, 161.0, 114.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 163.0, 161.0, 161.0, 160.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 106.0, 75.0, 76.0, 74.0, 74.0, 102.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 112.0, 84.0, 84.0, 84.0, 117.0, 84.0, 84.0, 128.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 145.0, 148.0, 148.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 50.0, 50.0, 50.0, 50.0, 50.0, 51.0, 49.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 61.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 76.0, 75.0, 83.0, 75.0, 83.0, 77.0, 76.0, 75.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 119.0, 118.0, 128.0, 117.0, 121.0, 122.0, 120.0, 170.0, 121.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 155.0, 155.0, 155.0, 161.0, 157.0, 160.0, 156.0, 157.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 62.0, 66.0, 64.0, 61.0, 61.0, 61.0, 65.0, 62.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1473.7000000000003, "alertThreshold": 2.0}]} 13:43:42 INFO - Return code: 0 13:43:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:43:42 INFO - # TBPL SUCCESS # 13:43:42 INFO - Running post-action listener: _resource_record_post_action 13:43:42 INFO - ##### 13:43:42 INFO - ##### Finished run-tests step (success) 13:43:42 INFO - ##### 13:43:42 INFO - Running post-run listener: _resource_record_post_run 13:43:42 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 16384; Write bytes: 572563456; Read time: 276; Write time: 491144 13:43:42 INFO - TinderboxPrint: CPU usage
13.0% 13:43:42 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 276 13:43:42 INFO - TinderboxPrint: I/O write bytes / time
572,563,456 / 491,144 13:43:42 INFO - TinderboxPrint: CPU idle
8,269.0 (87.0%) 13:43:42 INFO - TinderboxPrint: CPU user
1,194.9 (12.6%) 13:43:42 INFO - TinderboxPrint: Swap in / out
0 / 0 13:43:42 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 4096; Write bytes: 274960384; Read time: 112; Write time: 298692 13:43:42 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 297603072; Read time: 156; Write time: 192452 13:43:43 INFO - Running post-run listener: _upload_blobber_files 13:43:43 INFO - Blob upload gear active. 13:43:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:43:43 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 13:43:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:43:43 INFO - (blobuploader) - INFO - Open directory for files ... 13:43:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:43:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:44 INFO - (blobuploader) - INFO - Done attempting. 13:43:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:43:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:43:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:43:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:43:45 INFO - (blobuploader) - INFO - Done attempting. 13:43:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:43:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:43:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:44:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:02 INFO - (blobuploader) - INFO - Done attempting. 13:44:02 INFO - (blobuploader) - INFO - Iteration through files over. 13:44:02 INFO - Return code: 0 13:44:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:44:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:44:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9bd694282d55423076e13f3037e1470afe6dd9d2111307590678238eed3ad43bcc5872ec79d9455fac79458f7f2d02d6e00796b096f99761cfc1cd8be1a82e39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c11ac5edf7fa0d21ba196ff24ed1cf54aa3041586127ccfa23d79e7c19f8d06252e5d67240188050e949e338e29ac51c81f68ba95d610ed35f3c16f59512e78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c58110fa806c8dfa4a238b6066a4b2bdd9c3baa06e20498f8f42e72026cb1b09a3327a46ab1276297e06a8067f1721e258f4741dba24ffbc9e9b6383454abdb"} 13:44:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:44:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:44:02 INFO - Contents: 13:44:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9bd694282d55423076e13f3037e1470afe6dd9d2111307590678238eed3ad43bcc5872ec79d9455fac79458f7f2d02d6e00796b096f99761cfc1cd8be1a82e39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c11ac5edf7fa0d21ba196ff24ed1cf54aa3041586127ccfa23d79e7c19f8d06252e5d67240188050e949e338e29ac51c81f68ba95d610ed35f3c16f59512e78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c58110fa806c8dfa4a238b6066a4b2bdd9c3baa06e20498f8f42e72026cb1b09a3327a46ab1276297e06a8067f1721e258f4741dba24ffbc9e9b6383454abdb"} 13:44:02 INFO - Running post-run listener: copy_logs_to_upload_dir 13:44:02 INFO - Copying logs to upload dir... 13:44:02 INFO - mkdir: /builds/slave/test/build/upload/logs 13:44:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.587545 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-19 13:44:02.332865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 13:44:02.336360) ========= 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=337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9bd694282d55423076e13f3037e1470afe6dd9d2111307590678238eed3ad43bcc5872ec79d9455fac79458f7f2d02d6e00796b096f99761cfc1cd8be1a82e39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c11ac5edf7fa0d21ba196ff24ed1cf54aa3041586127ccfa23d79e7c19f8d06252e5d67240188050e949e338e29ac51c81f68ba95d610ed35f3c16f59512e78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c58110fa806c8dfa4a238b6066a4b2bdd9c3baa06e20498f8f42e72026cb1b09a3327a46ab1276297e06a8067f1721e258f4741dba24ffbc9e9b6383454abdb"} build_url:https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/f-4AUgY9QA6Kf3D-LZ0y-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9bd694282d55423076e13f3037e1470afe6dd9d2111307590678238eed3ad43bcc5872ec79d9455fac79458f7f2d02d6e00796b096f99761cfc1cd8be1a82e39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c11ac5edf7fa0d21ba196ff24ed1cf54aa3041586127ccfa23d79e7c19f8d06252e5d67240188050e949e338e29ac51c81f68ba95d610ed35f3c16f59512e78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c58110fa806c8dfa4a238b6066a4b2bdd9c3baa06e20498f8f42e72026cb1b09a3327a46ab1276297e06a8067f1721e258f4741dba24ffbc9e9b6383454abdb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 13:44:02.365546) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:44:02.366253) ========= 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=337fe3f4b1febe37b85fccc40000019e-1468958366.216945-1385881041 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:44:02.449399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 13:44:02.450099) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 13:44:02.450754) ========= ========= Total master_lag: 0.20 =========