builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-047 starttime: 1469595600.46 results: success (0) buildid: 20160726192227 builduid: d134e19ad4934f518da929ce8eb250fe revision: 34fab997a0a18bc72da09d0811ff98357fe0eb5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.462292) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.462823) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.463140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.609237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.609551) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 22:00:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-07-26 22:00:00 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124576 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.750561) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.751358) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017628 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.807461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:00.808159) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 34fab997a0a18bc72da09d0811ff98357fe0eb5f --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 34fab997a0a18bc72da09d0811ff98357fe0eb5f --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=03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 22:00:00,966 truncating revision to first 12 chars 2016-07-26 22:00:00,966 Setting DEBUG logging. 2016-07-26 22:00:00,966 attempt 1/10 2016-07-26 22:00:00,966 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/34fab997a0a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 22:00:01,265 unpacking tar archive at: mozilla-inbound-34fab997a0a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654001 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:01.477133) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:01.481557) ========= script_repo_revision: 34fab997a0a18bc72da09d0811ff98357fe0eb5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:01.482022) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:01.482347) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:00:01.519637) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-26 22:00:01.520080) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:01 INFO - MultiFileLogger online at 20160726 22:00:01 in /builds/slave/test 22:00:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:00:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:01 INFO - {'append_to_log': False, 22:00:01 INFO - 'base_work_dir': '/builds/slave/test', 22:00:01 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:00:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:01 INFO - 'branch': 'Mozilla-Inbound', 22:00:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:01 INFO - 'config_files': ('talos/linux_config.py',), 22:00:01 INFO - 'default_actions': ('clobber', 22:00:01 INFO - 'read-buildbot-config', 22:00:01 INFO - 'download-and-extract', 22:00:01 INFO - 'populate-webroot', 22:00:01 INFO - 'create-virtualenv', 22:00:01 INFO - 'install', 22:00:01 INFO - 'run-tests'), 22:00:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:01 INFO - 'download_minidump_stackwalk': True, 22:00:01 INFO - 'download_symbols': 'ondemand', 22:00:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:01 INFO - 'installer_path': 'installer.exe', 22:00:01 INFO - 'log_level': 'info', 22:00:01 INFO - 'log_name': 'talos', 22:00:01 INFO - 'log_to_console': True, 22:00:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:01 INFO - 'opt_config_files': (), 22:00:01 INFO - 'pip_index': False, 22:00:01 INFO - 'sps_profile': False, 22:00:01 INFO - 'sps_profile_interval': 0, 22:00:01 INFO - 'suite': 'dromaeojs-e10s', 22:00:01 INFO - 'system_bits': '32', 22:00:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:01 INFO - 'title': 'talos-linux64-ix-047', 22:00:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:01 INFO - 'use_talos_json': True, 22:00:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:01 INFO - 'work_dir': 'build'} 22:00:01 INFO - [mozharness: 2016-07-27 05:00:01.776730Z] Running clobber step. 22:00:01 INFO - Running pre-action listener: _resource_record_pre_action 22:00:01 INFO - Running main action method: clobber 22:00:01 INFO - rmtree: /builds/slave/test/build 22:00:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:02 INFO - Running post-action listener: _resource_record_post_action 22:00:02 INFO - [mozharness: 2016-07-27 05:00:02.250501Z] Finished clobber step (success) 22:00:02 INFO - [mozharness: 2016-07-27 05:00:02.250603Z] Running read-buildbot-config step. 22:00:02 INFO - Running pre-action listener: _resource_record_pre_action 22:00:02 INFO - Running main action method: read_buildbot_config 22:00:02 INFO - Using buildbot properties: 22:00:02 INFO - { 22:00:02 INFO - "project": "", 22:00:02 INFO - "product": "firefox", 22:00:02 INFO - "script_repo_revision": "production", 22:00:02 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:00:02 INFO - "repository": "", 22:00:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:00:02 INFO - "buildid": "20160726192227", 22:00:02 INFO - "pgo_build": "True", 22:00:02 INFO - "basedir": "/builds/slave/test", 22:00:02 INFO - "buildnumber": 911, 22:00:02 INFO - "slavename": "talos-linux64-ix-047", 22:00:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:00:02 INFO - "platform": "ubuntu64_hw", 22:00:02 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:00:02 INFO - "branch": "mozilla-inbound", 22:00:02 INFO - "repo_path": "integration/mozilla-inbound", 22:00:02 INFO - "revision": "34fab997a0a18bc72da09d0811ff98357fe0eb5f", 22:00:02 INFO - "stage_platform": "linux64-pgo", 22:00:02 INFO - "builduid": "d134e19ad4934f518da929ce8eb250fe", 22:00:02 INFO - "slavebuilddir": "test" 22:00:02 INFO - } 22:00:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:00:02 INFO - Running post-action listener: _resource_record_post_action 22:00:02 INFO - [mozharness: 2016-07-27 05:00:02.253054Z] Finished read-buildbot-config step (success) 22:00:02 INFO - [mozharness: 2016-07-27 05:00:02.253167Z] Running download-and-extract step. 22:00:02 INFO - Running pre-action listener: _resource_record_pre_action 22:00:02 INFO - Running main action method: download_and_extract 22:00:02 INFO - mkdir: /builds/slave/test/build/tests 22:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:02 INFO - https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:04 INFO - Downloaded 1361 bytes. 22:00:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:00:04 INFO - Using the following test package requirements: 22:00:04 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'jsshell-linux-x86_64.zip'], 22:00:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:04 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:04 INFO - https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:09 INFO - Downloaded 24349044 bytes. 22:00:09 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'] 22:00:09 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 22:00:10 INFO - Return code: 0 22:00:10 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 22:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:10 INFO - https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:00:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:15 INFO - Downloaded 24349044 bytes. 22:00:15 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'] 22:00:15 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 22:00:17 INFO - Return code: 0 22:00:17 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')]} 22:00:17 INFO - https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:00:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:17 INFO - Downloaded 14403317 bytes. 22:00:17 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'] 22:00:17 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 22:00:18 INFO - Return code: 0 22:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:18 INFO - https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:00:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qu4I-9htR7WZkv2UIhpOug/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 22:00:19 INFO - Downloaded 60910507 bytes. 22:00:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:19 INFO - mkdir: /builds/slave/test/properties 22:00:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:00:19 INFO - Writing to file /builds/slave/test/properties/build_url 22:00:19 INFO - Contents: 22:00:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:00:20 INFO - Running post-action listener: _resource_record_post_action 22:00:20 INFO - Running post-action listener: set_extra_try_arguments 22:00:20 INFO - [mozharness: 2016-07-27 05:00:20.067863Z] Finished download-and-extract step (success) 22:00:20 INFO - [mozharness: 2016-07-27 05:00:20.068096Z] Running populate-webroot step. 22:00:20 INFO - Running pre-action listener: _resource_record_pre_action 22:00:20 INFO - Running main action method: populate_webroot 22:00:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:20 INFO - '--tppagecycles', 22:00:20 INFO - '7'], 22:00:20 INFO - 'tests': ['tsvgm']}}, 22:00:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:00:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:00:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:00:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['damp', 'tps']}, 22:00:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'tests': ['damp', 'tps']}, 22:00:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['dromaeo_dom']}, 22:00:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['basic_compositor_video']}, 22:00:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:00:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['a11yr', 22:00:20 INFO - 'ts_paint', 22:00:20 INFO - 'tpaint', 22:00:20 INFO - 'sessionrestore', 22:00:20 INFO - 'sessionrestore_no_auto_restore', 22:00:20 INFO - 'tabpaint']}, 22:00:20 INFO - 'other-e10s': {'tests': ['a11yr', 22:00:20 INFO - 'ts_paint', 22:00:20 INFO - 'tpaint', 22:00:20 INFO - 'sessionrestore', 22:00:20 INFO - 'sessionrestore_no_auto_restore', 22:00:20 INFO - 'tabpaint']}, 22:00:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:00:20 INFO - 'ts_paint', 22:00:20 INFO - 'tpaint', 22:00:20 INFO - 'sessionrestore', 22:00:20 INFO - 'sessionrestore_no_auto_restore', 22:00:20 INFO - 'tabpaint']}, 22:00:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:00:20 INFO - 'ts_paint', 22:00:20 INFO - 'tpaint', 22:00:20 INFO - 'sessionrestore', 22:00:20 INFO - 'sessionrestore_no_auto_restore', 22:00:20 INFO - 'tabpaint']}, 22:00:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['a11yr', 22:00:20 INFO - 'ts_paint', 22:00:20 INFO - 'tpaint', 22:00:20 INFO - 'sessionrestore', 22:00:20 INFO - 'sessionrestore_no_auto_restore', 22:00:20 INFO - 'tabpaint']}, 22:00:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['a11yr', 22:00:20 INFO - 'ts_paint', 22:00:20 INFO - 'tpaint', 22:00:20 INFO - 'sessionrestore', 22:00:20 INFO - 'sessionrestore_no_auto_restore', 22:00:20 INFO - 'tabpaint']}, 22:00:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['tsvgx', 22:00:20 INFO - 'tsvgr_opacity', 22:00:20 INFO - 'tart', 22:00:20 INFO - 'tscrollx', 22:00:20 INFO - 'cart']}, 22:00:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:00:20 INFO - 'tsvgr_opacity', 22:00:20 INFO - 'tart', 22:00:20 INFO - 'tscrollx', 22:00:20 INFO - 'cart']}, 22:00:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'talos_options': ['--disable-e10s'], 22:00:20 INFO - 'tests': ['tp5o']}, 22:00:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'tests': ['tp5o']}, 22:00:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'talos_options': ['--disable-e10s', 22:00:20 INFO - '--xperf_path', 22:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:20 INFO - 'tests': ['tp5n']}, 22:00:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:20 INFO - 'talos_options': ['--xperf_path', 22:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:20 INFO - 'tests': ['tp5n']}}, 22:00:20 INFO - 'talos.zip': {'path': '', 22:00:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:20 INFO - Running post-action listener: _resource_record_post_action 22:00:20 INFO - [mozharness: 2016-07-27 05:00:20.087293Z] Finished populate-webroot step (success) 22:00:20 INFO - [mozharness: 2016-07-27 05:00:20.087382Z] Running create-virtualenv step. 22:00:20 INFO - Running pre-action listener: _resource_record_pre_action 22:00:20 INFO - Running main action method: create_virtualenv 22:00:20 INFO - Creating virtualenv /builds/slave/test/build/venv 22:00:20 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 22:00:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:00:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:00:20 INFO - Using real prefix '/usr' 22:00:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:00:20 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:22 INFO - Installing pip.................done. 22:00:22 INFO - Return code: 0 22:00:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:22 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:22 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 22:00:22 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 22:00:22 INFO - Using env: {'DISPLAY': ':0', 22:00:22 INFO - 'HOME': '/home/cltbld', 22:00:22 INFO - 'LANG': 'en_US.UTF-8', 22:00:22 INFO - 'LANGUAGE': 'en_US:en', 22:00:22 INFO - 'LOGNAME': 'cltbld', 22:00:22 INFO - 'MAIL': '/var/mail/cltbld', 22:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:22 INFO - 'MOZ_NO_REMOTE': '1', 22:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:22 INFO - 'NO_EM_RESTART': '1', 22:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:22 INFO - 'PWD': '/builds/slave/test', 22:00:22 INFO - 'SHELL': '/bin/bash', 22:00:22 INFO - 'SHLVL': '1', 22:00:22 INFO - 'TERM': 'linux', 22:00:22 INFO - 'TMOUT': '86400', 22:00:22 INFO - 'USER': 'cltbld', 22:00:22 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:22 INFO - '_': '/tools/buildbot/bin/python'} 22:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:22 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 22:00:22 INFO - Cleaning up... 22:00:22 INFO - Return code: 0 22:00:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:22 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:22 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 22:00:22 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 22:00:22 INFO - Using env: {'DISPLAY': ':0', 22:00:22 INFO - 'HOME': '/home/cltbld', 22:00:22 INFO - 'LANG': 'en_US.UTF-8', 22:00:22 INFO - 'LANGUAGE': 'en_US:en', 22:00:22 INFO - 'LOGNAME': 'cltbld', 22:00:22 INFO - 'MAIL': '/var/mail/cltbld', 22:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:22 INFO - 'MOZ_NO_REMOTE': '1', 22:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:22 INFO - 'NO_EM_RESTART': '1', 22:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:22 INFO - 'PWD': '/builds/slave/test', 22:00:22 INFO - 'SHELL': '/bin/bash', 22:00:22 INFO - 'SHLVL': '1', 22:00:22 INFO - 'TERM': 'linux', 22:00:22 INFO - 'TMOUT': '86400', 22:00:22 INFO - 'USER': 'cltbld', 22:00:22 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:22 INFO - '_': '/tools/buildbot/bin/python'} 22:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:23 INFO - Downloading/unpacking psutil>=3.1.1 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:25 INFO - Installing collected packages: psutil 22:00:25 INFO - Running setup.py install for psutil 22:00:25 INFO - building 'psutil._psutil_linux' extension 22:00:25 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 22:00:25 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 22:00:25 INFO - building 'psutil._psutil_posix' extension 22:00:25 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 22:00:25 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 22:00:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:25 INFO - Successfully installed psutil 22:00:25 INFO - Cleaning up... 22:00:25 INFO - Return code: 0 22:00:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:25 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:25 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 22:00:25 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 22:00:25 INFO - Using env: {'DISPLAY': ':0', 22:00:25 INFO - 'HOME': '/home/cltbld', 22:00:25 INFO - 'LANG': 'en_US.UTF-8', 22:00:25 INFO - 'LANGUAGE': 'en_US:en', 22:00:25 INFO - 'LOGNAME': 'cltbld', 22:00:25 INFO - 'MAIL': '/var/mail/cltbld', 22:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:25 INFO - 'MOZ_NO_REMOTE': '1', 22:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:25 INFO - 'NO_EM_RESTART': '1', 22:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:25 INFO - 'PWD': '/builds/slave/test', 22:00:25 INFO - 'SHELL': '/bin/bash', 22:00:25 INFO - 'SHLVL': '1', 22:00:25 INFO - 'TERM': 'linux', 22:00:25 INFO - 'TMOUT': '86400', 22:00:25 INFO - 'USER': 'cltbld', 22:00:25 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:25 INFO - '_': '/tools/buildbot/bin/python'} 22:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:00:26 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 22:00:26 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 22:00:26 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 22:00:26 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 22:00:26 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 22:00:26 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 22:00:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:00:28 INFO - Installing collected packages: mozsystemmonitor 22:00:28 INFO - Running setup.py install for mozsystemmonitor 22:00:28 INFO - Successfully installed mozsystemmonitor 22:00:28 INFO - Cleaning up... 22:00:28 INFO - Return code: 0 22:00:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:00:28 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')]} 22:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:28 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')]} 22:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:28 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:28 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 22:00:28 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 22:00:28 INFO - Using env: {'DISPLAY': ':0', 22:00:28 INFO - 'HOME': '/home/cltbld', 22:00:28 INFO - 'LANG': 'en_US.UTF-8', 22:00:28 INFO - 'LANGUAGE': 'en_US:en', 22:00:28 INFO - 'LOGNAME': 'cltbld', 22:00:28 INFO - 'MAIL': '/var/mail/cltbld', 22:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:28 INFO - 'MOZ_NO_REMOTE': '1', 22:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:28 INFO - 'NO_EM_RESTART': '1', 22:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:28 INFO - 'PWD': '/builds/slave/test', 22:00:28 INFO - 'SHELL': '/bin/bash', 22:00:28 INFO - 'SHLVL': '1', 22:00:28 INFO - 'TERM': 'linux', 22:00:28 INFO - 'TMOUT': '86400', 22:00:28 INFO - 'USER': 'cltbld', 22:00:28 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:28 INFO - '_': '/tools/buildbot/bin/python'} 22:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:28 INFO - Downloading/unpacking jsonschema==2.5.1 22:00:28 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 22:00:28 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 22:00:28 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 22:00:28 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 22:00:28 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 22:00:28 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 22:00:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:00:30 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 22:00:30 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 22:00:30 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 22:00:30 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 22:00:30 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 22:00:30 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 22:00:30 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:30 INFO - warning: no files found matching '*.txt' 22:00:30 INFO - no previously-included directories found matching 'build' 22:00:30 INFO - no previously-included directories found matching 'dist' 22:00:30 INFO - no previously-included directories found matching '.git*' 22:00:30 INFO - Installing collected packages: jsonschema, functools32 22:00:30 INFO - Running setup.py install for functools32 22:00:30 INFO - warning: no files found matching '*.txt' 22:00:30 INFO - no previously-included directories found matching 'build' 22:00:30 INFO - no previously-included directories found matching 'dist' 22:00:30 INFO - no previously-included directories found matching '.git*' 22:00:30 INFO - Successfully installed jsonschema functools32 22:00:30 INFO - Cleaning up... 22:00:30 INFO - Return code: 0 22:00:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:00:30 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')]} 22:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:30 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')]} 22:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:30 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:30 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 22:00:30 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 22:00:30 INFO - Using env: {'DISPLAY': ':0', 22:00:30 INFO - 'HOME': '/home/cltbld', 22:00:30 INFO - 'LANG': 'en_US.UTF-8', 22:00:30 INFO - 'LANGUAGE': 'en_US:en', 22:00:30 INFO - 'LOGNAME': 'cltbld', 22:00:30 INFO - 'MAIL': '/var/mail/cltbld', 22:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:30 INFO - 'MOZ_NO_REMOTE': '1', 22:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:30 INFO - 'NO_EM_RESTART': '1', 22:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:30 INFO - 'PWD': '/builds/slave/test', 22:00:30 INFO - 'SHELL': '/bin/bash', 22:00:30 INFO - 'SHLVL': '1', 22:00:30 INFO - 'TERM': 'linux', 22:00:30 INFO - 'TMOUT': '86400', 22:00:30 INFO - 'USER': 'cltbld', 22:00:30 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:30 INFO - '_': '/tools/buildbot/bin/python'} 22:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:31 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:31 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 22:00:31 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 22:00:31 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 22:00:31 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 22:00:31 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 22:00:31 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 22:00:33 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:00:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 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 22:00:33 INFO - Downloading docopt-0.6.1.tar.gz 22:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:33 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:33 INFO - Running setup.py install for blobuploader 22:00:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:00:33 INFO - Running setup.py install for requests 22:00:34 INFO - Running setup.py install for docopt 22:00:34 INFO - Successfully installed blobuploader requests docopt 22:00:34 INFO - Cleaning up... 22:00:34 INFO - Return code: 0 22:00:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:34 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:34 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 22:00:34 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 22:00:34 INFO - Using env: {'DISPLAY': ':0', 22:00:34 INFO - 'HOME': '/home/cltbld', 22:00:34 INFO - 'LANG': 'en_US.UTF-8', 22:00:34 INFO - 'LANGUAGE': 'en_US:en', 22:00:34 INFO - 'LOGNAME': 'cltbld', 22:00:34 INFO - 'MAIL': '/var/mail/cltbld', 22:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:34 INFO - 'MOZ_NO_REMOTE': '1', 22:00:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:34 INFO - 'NO_EM_RESTART': '1', 22:00:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:34 INFO - 'PWD': '/builds/slave/test', 22:00:34 INFO - 'SHELL': '/bin/bash', 22:00:34 INFO - 'SHLVL': '1', 22:00:34 INFO - 'TERM': 'linux', 22:00:34 INFO - 'TMOUT': '86400', 22:00:34 INFO - 'USER': 'cltbld', 22:00:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:34 INFO - '_': '/tools/buildbot/bin/python'} 22:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:34 INFO - Running setup.py (path:/tmp/pip-C394QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:34 INFO - Running setup.py (path:/tmp/pip-edPTIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:34 INFO - Running setup.py (path:/tmp/pip-sOTlLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:34 INFO - Running setup.py (path:/tmp/pip-gxxEUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:34 INFO - Running setup.py (path:/tmp/pip-gTcAv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:35 INFO - Running setup.py (path:/tmp/pip-JbdNMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:35 INFO - Running setup.py (path:/tmp/pip-cpwFFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:35 INFO - Running setup.py (path:/tmp/pip-VIBE1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:35 INFO - Running setup.py (path:/tmp/pip-I67e4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:35 INFO - Running setup.py (path:/tmp/pip-bR_QOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:35 INFO - Running setup.py (path:/tmp/pip-gGo6EM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:35 INFO - Running setup.py (path:/tmp/pip-d1m2wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:35 INFO - Running setup.py (path:/tmp/pip-t3DHZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:35 INFO - Running setup.py (path:/tmp/pip-tUwllN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:35 INFO - Running setup.py (path:/tmp/pip-7mE8_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:35 INFO - Running setup.py (path:/tmp/pip-81MUon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:35 INFO - Running setup.py (path:/tmp/pip-nTid8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:35 INFO - Running setup.py install for manifestparser 22:00:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:00:35 INFO - Running setup.py install for mozcrash 22:00:36 INFO - Running setup.py install for mozdebug 22:00:36 INFO - Running setup.py install for mozdevice 22:00:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Running setup.py install for mozfile 22:00:36 INFO - Running setup.py install for mozhttpd 22:00:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Running setup.py install for mozinfo 22:00:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Running setup.py install for mozInstall 22:00:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Running setup.py install for mozleak 22:00:36 INFO - Running setup.py install for mozlog 22:00:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:00:36 INFO - Running setup.py install for moznetwork 22:00:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:00:37 INFO - Running setup.py install for mozprocess 22:00:37 INFO - Running setup.py install for mozprofile 22:00:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:00:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:00:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:00:37 INFO - Running setup.py install for mozrunner 22:00:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:00:37 INFO - Running setup.py install for mozscreenshot 22:00:37 INFO - Running setup.py install for moztest 22:00:37 INFO - Running setup.py install for mozversion 22:00:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:00:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:37 INFO - Cleaning up... 22:00:37 INFO - Return code: 0 22:00:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:37 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:37 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 22:00:37 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 22:00:37 INFO - Using env: {'DISPLAY': ':0', 22:00:37 INFO - 'HOME': '/home/cltbld', 22:00:37 INFO - 'LANG': 'en_US.UTF-8', 22:00:37 INFO - 'LANGUAGE': 'en_US:en', 22:00:37 INFO - 'LOGNAME': 'cltbld', 22:00:37 INFO - 'MAIL': '/var/mail/cltbld', 22:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:37 INFO - 'MOZ_NO_REMOTE': '1', 22:00:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:37 INFO - 'NO_EM_RESTART': '1', 22:00:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:37 INFO - 'PWD': '/builds/slave/test', 22:00:37 INFO - 'SHELL': '/bin/bash', 22:00:37 INFO - 'SHLVL': '1', 22:00:37 INFO - 'TERM': 'linux', 22:00:37 INFO - 'TMOUT': '86400', 22:00:37 INFO - 'USER': 'cltbld', 22:00:37 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:37 INFO - '_': '/tools/buildbot/bin/python'} 22:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:37 INFO - Running setup.py (path:/tmp/pip-3lIGrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:37 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)) 22:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:37 INFO - Running setup.py (path:/tmp/pip-_0NAj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:38 INFO - Running setup.py (path:/tmp/pip-CzCHt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:38 INFO - Running setup.py (path:/tmp/pip-gHW92H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:38 INFO - Running setup.py (path:/tmp/pip-w7xE_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:38 INFO - Running setup.py (path:/tmp/pip-eI5Uwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:38 INFO - Running setup.py (path:/tmp/pip-xqHYqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:38 INFO - Running setup.py (path:/tmp/pip-2tBGYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:38 INFO - Running setup.py (path:/tmp/pip-GtlbQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:38 INFO - Running setup.py (path:/tmp/pip-lnR2Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:38 INFO - Running setup.py (path:/tmp/pip-Vqq0ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:38 INFO - Running setup.py (path:/tmp/pip-EgAcNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:38 INFO - Running setup.py (path:/tmp/pip-I59gzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:38 INFO - Running setup.py (path:/tmp/pip-3l6Vjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:38 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)) 22:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:38 INFO - Running setup.py (path:/tmp/pip-kjgGOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:39 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)) 22:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:39 INFO - Running setup.py (path:/tmp/pip-XFIqKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:39 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)) 22:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:39 INFO - Running setup.py (path:/tmp/pip-0r70bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:39 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)) 22:00:39 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)) 22:00:39 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)) 22:00:39 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)) 22:00:39 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)) 22:00:39 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)) 22:00:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:41 INFO - Downloading blessings-1.6.tar.gz 22:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:00:41 INFO - Installing collected packages: blessings 22:00:41 INFO - Running setup.py install for blessings 22:00:41 INFO - Successfully installed blessings 22:00:41 INFO - Cleaning up... 22:00:41 INFO - Return code: 0 22:00:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:00:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:41 INFO - Reading from file tmpfile_stdout 22:00:41 INFO - Current package versions: 22:00:41 INFO - argparse == 1.2.1 22:00:41 INFO - blessings == 1.6 22:00:41 INFO - blobuploader == 1.2.4 22:00:41 INFO - docopt == 0.6.1 22:00:41 INFO - functools32 == 3.2.3-2 22:00:41 INFO - jsonschema == 2.5.1 22:00:41 INFO - manifestparser == 1.1 22:00:41 INFO - mozInstall == 1.12 22:00:41 INFO - mozcrash == 0.17 22:00:41 INFO - mozdebug == 0.1 22:00:41 INFO - mozdevice == 0.48 22:00:41 INFO - mozfile == 1.2 22:00:41 INFO - mozhttpd == 0.7 22:00:41 INFO - mozinfo == 0.9 22:00:41 INFO - mozleak == 0.1 22:00:41 INFO - mozlog == 3.3 22:00:41 INFO - moznetwork == 0.27 22:00:41 INFO - mozprocess == 0.23 22:00:41 INFO - mozprofile == 0.28 22:00:41 INFO - mozrunner == 6.12 22:00:41 INFO - mozscreenshot == 0.1 22:00:41 INFO - mozsystemmonitor == 0.3 22:00:41 INFO - moztest == 0.7 22:00:41 INFO - mozversion == 1.4 22:00:41 INFO - psutil == 3.1.1 22:00:41 INFO - requests == 1.2.3 22:00:41 INFO - wsgiref == 0.1.2 22:00:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:41 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')]} 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:41 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')]} 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:41 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:41 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 22:00:41 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 22:00:41 INFO - Using env: {'DISPLAY': ':0', 22:00:41 INFO - 'HOME': '/home/cltbld', 22:00:41 INFO - 'LANG': 'en_US.UTF-8', 22:00:41 INFO - 'LANGUAGE': 'en_US:en', 22:00:41 INFO - 'LOGNAME': 'cltbld', 22:00:41 INFO - 'MAIL': '/var/mail/cltbld', 22:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:41 INFO - 'MOZ_NO_REMOTE': '1', 22:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:41 INFO - 'NO_EM_RESTART': '1', 22:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:41 INFO - 'PWD': '/builds/slave/test', 22:00:41 INFO - 'SHELL': '/bin/bash', 22:00:41 INFO - 'SHLVL': '1', 22:00:41 INFO - 'TERM': 'linux', 22:00:41 INFO - 'TMOUT': '86400', 22:00:41 INFO - 'USER': 'cltbld', 22:00:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:41 INFO - '_': '/tools/buildbot/bin/python'} 22:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 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)) 22:00:41 INFO - Cleaning up... 22:00:41 INFO - Return code: 0 22:00:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:00:41 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')]} 22:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:41 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')]} 22:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:41 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 0x105a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11bd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, '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': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', '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 22:00:41 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 22:00:41 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 22:00:41 INFO - Using env: {'DISPLAY': ':0', 22:00:41 INFO - 'HOME': '/home/cltbld', 22:00:41 INFO - 'LANG': 'en_US.UTF-8', 22:00:41 INFO - 'LANGUAGE': 'en_US:en', 22:00:41 INFO - 'LOGNAME': 'cltbld', 22:00:41 INFO - 'MAIL': '/var/mail/cltbld', 22:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:41 INFO - 'MOZ_NO_REMOTE': '1', 22:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:41 INFO - 'NO_EM_RESTART': '1', 22:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:41 INFO - 'PWD': '/builds/slave/test', 22:00:41 INFO - 'SHELL': '/bin/bash', 22:00:41 INFO - 'SHLVL': '1', 22:00:41 INFO - 'TERM': 'linux', 22:00:41 INFO - 'TMOUT': '86400', 22:00:41 INFO - 'USER': 'cltbld', 22:00:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:41 INFO - '_': '/tools/buildbot/bin/python'} 22:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:00:41 INFO - Cleaning up... 22:00:41 INFO - Return code: 0 22:00:41 INFO - Running post-action listener: _resource_record_post_action 22:00:41 INFO - Running post-action listener: _start_resource_monitoring 22:00:41 INFO - Starting resource monitoring. 22:00:41 INFO - [mozharness: 2016-07-27 05:00:41.972568Z] Finished create-virtualenv step (success) 22:00:41 INFO - [mozharness: 2016-07-27 05:00:41.973046Z] Running install step. 22:00:41 INFO - Running pre-action listener: _resource_record_pre_action 22:00:41 INFO - Running main action method: install 22:00:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:42 INFO - Reading from file tmpfile_stdout 22:00:42 INFO - Detecting whether we're running mozinstall >=1.0... 22:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:00:42 INFO - Reading from file tmpfile_stdout 22:00:42 INFO - Output received: 22:00:42 INFO - Usage: mozinstall [options] installer 22:00:42 INFO - Options: 22:00:42 INFO - -h, --help show this help message and exit 22:00:42 INFO - -d DEST, --destination=DEST 22:00:42 INFO - Directory to install application into. [default: 22:00:42 INFO - "/builds/slave/test"] 22:00:42 INFO - --app=APP Application being installed. [default: firefox] 22:00:42 INFO - mkdir: /builds/slave/test/build/application 22:00:42 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'] 22:00:42 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 22:00:59 INFO - Reading from file tmpfile_stdout 22:00:59 INFO - Output received: 22:00:59 INFO - /builds/slave/test/build/application/firefox/firefox 22:00:59 INFO - Running post-action listener: _resource_record_post_action 22:00:59 INFO - [mozharness: 2016-07-27 05:00:59.495440Z] Finished install step (success) 22:00:59 INFO - [mozharness: 2016-07-27 05:00:59.495721Z] Running run-tests step. 22:00:59 INFO - Running pre-action listener: _resource_record_pre_action 22:00:59 INFO - Running main action method: run_tests 22:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:00:59 INFO - Python 2.7.3 22:00:59 INFO - Return code: 0 22:00:59 INFO - grabbing minidump binary from tooltool 22:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:59 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 0x1318570>, '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 0x1319470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1319900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:00:59 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 22:00:59 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 22:00:59 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 22:00:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:00:59 INFO - Return code: 0 22:00:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:00:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:00:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:00:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:00:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:00:59 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:00:59 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:00:59 INFO - Using env: {'DISPLAY': ':0', 22:00:59 INFO - 'HOME': '/home/cltbld', 22:00:59 INFO - 'LANG': 'en_US.UTF-8', 22:00:59 INFO - 'LANGUAGE': 'en_US:en', 22:00:59 INFO - 'LOGNAME': 'cltbld', 22:00:59 INFO - 'MAIL': '/var/mail/cltbld', 22:00:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:00:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:59 INFO - 'MOZ_NO_REMOTE': '1', 22:00:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:59 INFO - 'NO_EM_RESTART': '1', 22:00:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:59 INFO - 'PWD': '/builds/slave/test', 22:00:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:00:59 INFO - 'SHELL': '/bin/bash', 22:00:59 INFO - 'SHLVL': '1', 22:00:59 INFO - 'TERM': 'linux', 22:00:59 INFO - 'TMOUT': '86400', 22:00:59 INFO - 'USER': 'cltbld', 22:00:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349', 22:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:59 INFO - '_': '/tools/buildbot/bin/python'} 22:00:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:00:59 INFO - mozversion application_buildid: 20160726192227 22:00:59 INFO - mozversion application_changeset: 34fab997a0a18bc72da09d0811ff98357fe0eb5f 22:00:59 INFO - mozversion application_display_name: Nightly 22:00:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:00:59 INFO - mozversion application_name: Firefox 22:00:59 INFO - mozversion application_remotingname: firefox 22:00:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:00:59 INFO - mozversion application_vendor: Mozilla 22:00:59 INFO - mozversion application_version: 50.0a1 22:00:59 INFO - mozversion platform_buildid: 20160726192227 22:00:59 INFO - mozversion platform_changeset: 34fab997a0a18bc72da09d0811ff98357fe0eb5f 22:00:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:00:59 INFO - mozversion platform_version: 50.0a1 22:00:59 INFO - using testdate: 1469595659 22:00:59 INFO - actual date: 1469595659 22:00:59 INFO - starting webserver on 'localhost:48496' 22:00:59 INFO - SUITE-START | Running 2 tests 22:00:59 INFO - TEST-START | dromaeo_css 22:00:59 INFO - operating with platform_type : linux_ 22:00:59 INFO - Initialising browser for dromaeo_css test... 22:00:59 INFO - TEST-INFO | started process 4223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1FXojo/profile http://localhost:48496/getInfo.html) 22:01:03 INFO - PROCESS | 4223 | JavaScript error: , line 0: uncaught exception: undefined 22:01:04 INFO - PROCESS | 4223 | __metrics Screen width/height:1600/1200 22:01:04 INFO - PROCESS | 4223 | colorDepth:24 22:01:04 INFO - PROCESS | 4223 | Browser inner width/height: 1024/697 22:01:04 INFO - PROCESS | 4223 | __metrics 22:01:05 ERROR - PROCESS | 4223 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:01:05 INFO - TEST-INFO | 4223: exit 0 22:01:05 INFO - Browser initialized. 22:01:05 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:05 INFO - TEST-INFO | started process 4345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1FXojo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:17 INFO - PROCESS | 4345 | RSS: Main: 167661568 22:01:17 INFO - PROCESS | 4345 | 22:02:07 INFO - PROCESS | 4345 | 1469595727317 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] 22:04:15 INFO - PROCESS | 4345 | Cycle 1(1): loaded http://localhost:48496/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48496/tests/dromaeo/cssquery-ext.html) 22:04:16 INFO - PROCESS | 4345 | RSS: Main: 172351488 22:04:16 INFO - PROCESS | 4345 | 22:07:18 INFO - PROCESS | 4345 | Cycle 1(1): loaded http://localhost:48496/tests/dromaeo/cssquery-ext.html (next: http://localhost:48496/tests/dromaeo/cssquery-jquery.html) 22:07:19 INFO - PROCESS | 4345 | RSS: Main: 169594880 22:07:19 INFO - PROCESS | 4345 | 22:10:21 INFO - PROCESS | 4345 | Cycle 1(1): loaded http://localhost:48496/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48496/tests/dromaeo/cssquery-mootools.html) 22:10:21 INFO - PROCESS | 4345 | RSS: Main: 174084096 22:10:21 INFO - PROCESS | 4345 | 22:13:23 INFO - PROCESS | 4345 | Cycle 1(1): loaded http://localhost:48496/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48496/tests/dromaeo/cssquery-prototype.html) 22:13:24 INFO - PROCESS | 4345 | RSS: Main: 168251392 22:13:24 INFO - PROCESS | 4345 | 22:16:26 INFO - PROCESS | 4345 | Cycle 1(1): loaded http://localhost:48496/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48496/tests/dromaeo/cssquery-yui.html) 22:16:27 INFO - PROCESS | 4345 | RSS: Main: 170782720 22:16:27 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | Cycle 1(1): loaded http://localhost:48496/tests/dromaeo/cssquery-yui.html (next: http://localhost:48496/tests/dromaeo/cssquery-dojo.html) 22:19:30 INFO - PROCESS | 4345 | RSS: Main: 173760512 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | __start_tp_report 22:19:30 INFO - PROCESS | 4345 | _x_x_mozilla_page_load 22:19:30 INFO - PROCESS | 4345 | _x_x_mozilla_page_load_details 22:19:30 INFO - PROCESS | 4345 | |i|pagename|runs| 22:19:30 INFO - PROCESS | 4345 | |0;dojo.html;3073.6618971913094;3106.7359274461655;3110.0980715592473;3123.187971127506;3138.890138845141;5435.157550579666;5458.9454105458935;5470.261514695515;5547.33431988162;5642.210090587321;5309.707966061865;5420.4850539198715;5473.589480788937;5494.725263736817;5573.637713548616;4879.53644403782;4907.362042934414;5019.272205530196;5057.949420505803;5100.668456550312;4902.436219834711;5100.15847385181;5101.005303965728;5110.821121260757;5113.437521872591;4889.682170658896;4949.505049495057;4974.030064137512;5025.84922452327;5055.873603159914;4978.427480839714;4978.576820970222;4998.600111991032;5016.598672106223;5030.37120359955;4947.134251505972;4948.67833590819;4955.306257124005;4964.255361695738;5014.172661510846;5363.839084827461;5439.836804895859;5451.400345961941;5494.066008778517;5494.890102197974;5885.087811389241;5908.350081491032;5913.172155898178;5915.438033386821;5940.495057920082;5822.184894114827;5873.353931067579;5882.29412470502;5882.823515294548;5903.675297858616;5251.4485978972425;5412.215228791803;5440.102383106782;5452.672839629635;5525.751341189656;5991.370906054889;5994.430529099728;5996.610220335664;6005.189299444519;6033.637981721111;5776.537876969833;5780.277465316835;5782.190493330937;5784.8264552062665;5785.218995435646;5704;5767.163761254595;5796.275465566804;5803.6807975560905;5838;5579.414161513064;5780.6531608102805;5823.272090988627;5839.182514447981;5888.970555147197;5835.4748072673665;5850.707464626752;5871.7651293947765;5888.5289176865745;5898.911516327258;4310.288802347577;4359.757469121285;4413.911721765547;4459.375687403766;4463.575960283768;3899.6880249579967;3952.960470395317;3965.0682089708894;3971.0866500705;3976.940345894814;5483.396826349099;5524.977879092381;5537.086380747212;5550.139728342114;5603.6077474577205;5710.571707121982;5823.679697616672;5866.941330586725;5892.292924849045;5899.587028908021;5576.609637325349;5600.831975040755;5605.495505404451;5627.240322556402;5642.633228840113;5036.748162591855;5074.67014644047;5076.847694569169;5080.669756465818;5113.897722045538;5883.941160588425;5921.2302400688495;6049.03215485529;6053.878922421527;6059.182010428536;5796.8550786229835;5867.501262392744;5876.265466816648;5918.378570250234;5918.881622367442;5972.641641501524;6000.21997140369;6024;6027.879442411039;6035.094735789667;5566.582506311962;5689.175069614966;5768.884622307446;5775.48020678141;5788.160716696139;5826.155207494805;5859.179714840011;5889.852753681021;5929.16991621182;5933.02105152645;5723.3418156912485;5734.684592347461;5740.1102829061565;5742.885142297214;5746.913796293143;5563.860903477445;5702.002149623882;5709.457601527848;5714.542836572981;5721.8569535761935;5670.489655930986;5684.118961560965;5687.1753595729215;5706.885862282648;5721.456461636138;5774.711264436677;5797.246357973437;5819.5344372450945;5825.0388685866355;5834.533237341087;5461.15352120422;5658.745356459051;5669.177969194526;5679.261695979496;5706.115552089433 22:19:30 INFO - PROCESS | 4345 | |1;ext.html;548.7377110622062;587.6591576885406;589.4724221821467;609.6585911889342;617.2099712368171;39799.403008954854;44330;44515;44646.107077858425;44738;44499;45061;45080.54919450804;45135.322970155474;45138.32292515615;8817.250533704642;8961.507117108556;8970.775730606754;8973.057828927966;8981.056989016124;8869.068747781486;8978.86531702025;8979.37144399889;8985.281177505816;8986.775330616754;8903.866442003375;8982.146696063863;8992.325575581825;9006.234470070052;9017.639294428214;8904.287656987426;8909.643614255421;8955.05971872957;8956.641734330618;8962;8824.558772061371;8857.202851743374;8880.644774209024;8889.822203555957;8894.421862578976;42008.579914200775;42403.57596424027;42457.57542424567;42527;42598.78700606508;2950.1002194330936;3444.121748954001;3454.827258637058;3471.9063494998995;3580.6777390034763;33347.16632084193;33371.49942750861;33476.49785253223;33701.483433245565;34895.82552087249;4460.420145381112;4480.238359478896;4492.326151077365;4519.525449827786;4578.725276483422;344235;345134.27432862925;345512;346350;348598;59877.401225988055;60119.098213526835;60128.098078528856;60261;60340.698296509116;36090.45864312037;36126;36655.26689466196;38446;38529.422058669144;17786.822131778776;17952.19215135322;17988;18013.18940647674;18048.09759512019;13710.24593647359;13837.169769813843;13837.169769813843;13840;13943.372548235353;19568.217271309273;19763;19840.702389464168;19856.205751770056;19991.900040499997;7033.4724895633035;7112.324329188718;7266.818329541804;7347.7795666130105;7429.888551671621;952.7713348796377;952.9666461673808;1058.4972138234432;1067.0503252105632;1109.6504601050804;31506.52740208899;33047.3390532188;33213.50179747306;33238.833805830815;33358.16604584905;1102.7518808268235;1111.6609434122508;1114.7213196700825;1155.6937411585936;1191.2614179208772;16908.661826763397;17208.913955430395;17344.30622775075;17353.132343382782;17395.130243488027;608201;612505;612926;621919;627199;61271.38728612746;62126.37873621206;62129.68935155295;62137.37862621316;62246;1331.9600411987462;1364.9727005459836;1365.044468871774;1366.0369439545098;1382.198324971514;30757.38485230238;32349.838250808596;32405.51391729173;32664.34671306513;33292.167695807795;51447.48552514427;61544;62782;62957.11128666147;63257.68371158115;490546;493216;493338;494942;495435.2738835681;45099.549004509536;45965.77017114893;46062.30906536338;46170.307445387676;46653.76673116613;50996;51158.23262651137;51396.74301628468;51797.741011294704;52456;42640.14719705527;43069.569304307806;43165.136697266506;43890.56109438992;45767.54232457633;43915;44246.336304954806;44432.777836111905;45073.54926450693;45715.77142114268;43635;44111.31710663058;45329.773351134354;45992.08015839598;46077.078458429976;39992.80003599964;42188;42192.1561568773;47107.764461177474;48001.75999119982;33392;35381.646183537836;35539;35684.28631427305;36086.27827443489 22:19:30 INFO - PROCESS | 4345 | |2;jquery.html;380.83433706337763;387.71115518938393;399.8880313512217;400.2155774681624;412.2476480423226;18506;19930.70103948442;20088.196472141117;20237.59524809503;20616;18378.540536486595;18734.906325468386;18882;19468.9026554867;19789.40631781045;5053.0399224147295;5098.745062746848;5256.106461901486;5301.0988132017455;5350.625456218067;5320.787168513274;5377.220303056055;5385.21914322423;5387.703676297802;5391.56867450603;5292.179712144604;5347.866303342409;5348.144296912495;5365.570754339663;5394.02907476654;5407.810726624549;5414.3232095988005;5414.377346605132;5420.972895135519;5422.05114105032;5228.08508511009;5230.110881150214;5282.788688452457;5287.867803304948;5302.602304827154;13898.23559704216;14056.297185140702;14098.788518172236;21530.492865499422;23021.424464388358;2170.7937745914114;2188.872730543771;2253.5830871288863;2287.2795069553035;2333.1484008337006;10046.497675116216;10251.128654064414;10257.743556411075;10447.947760261226;10559.736006599896;1890.1399863062275;1958.6670266054805;1967.1541237267968;2005.1678553400322;2009.4976255936017;82140.94186346795;83473.58263208705;84273.57863210706;84318.99999999939;84721.57639211827;10543.26197166191;11047.116230701524;11118.110551155889;11230.55077796879;11490.080793536497;15540.300686469136;15558.299876505584;15575.844241557666;15588.14265215424;15614.921925390527;8176.550289734003;8265.876011859827;8273.007239131342;8424.4945303282;8549.187827156411;7915.722949696733;7973.362131029619;8173.713920012771;8190.7133250335955;8199.672013119527;4588.242939914877;4602;4668.50980647034;4699.412573428322;4790.257510085942;1938.4378530226077;1957.2758079510672;1959.4219705187004;1992.7807941126464;2006.4883454719245;933.8085692433035;940.9623615055322;991.3803872579638;1014.8832884218261;1070.8340207267886;19668.11493482768;19750.11124499401;19857.900710496357;20033.899830500755;20056;949.188443880486;965.6089283840056;981.3768257156756;990.7126933189294;1006.6828948881079;8260.339172866277;10587.470626468647;11028.558857645765;11128.833067503994;11143;477876;492446;505660;512632;528958;91688.54155729178;92994.53502732443;97289;99109;99734.50132749435;969.2149359019216;1008.2538921198407;1025.6717850287837;1026.0867827633413;1033.813913495563;16903.66192676164;17149.485515434557;17628.735568966218;17756.200970956597;18152.636947261246;38846.6115338843;43129.56870431256;43983.340249895635;44713.329300061174;47019.529804702885;60049;60400;60565;61769.69115154396;62007;8424.157584241526;8446.155384461505;8629.09394513567;8686.522241276538;8694.91305086941;12238.510459581517;12370.587735391513;12406.193597416139;13246.20522768637;13450.798238026242;11639.88360116388;11662.70843228926;11680.649580512598;11751.647450576496;12178.939105304416;11632.418379081182;11696.239744416585;11863.228890122115;12019.579314723942;12141.271523708252;11729.589464368706;11814.291142531476;11842.52629894829;11990;12243.449044792827;8427.789305267417;8721.694740684046;8729.388942773945;8855.114488551351;8960.641574337214;10643.574257029632;11626.069914406997;11782.882171178178;11856.11079169083;11918.940405297919 22:19:30 INFO - PROCESS | 4345 | |3;mootools.html;1470.1767010474134;1493.537003528906;1611.2507683926979;1798.100949525238;1802.0629272778147;7217.27827217278;8041.51750894946;8115.715949941753;8491.957540212297;8516.701915432961;8103.959480202606;8404.747857564284;8423.663053477854;8458.196471335228;8476.745697629065;2271.466205441724;2298.4713515891353;2311.1910831209025;2311.56080344734;2311.595470792613;2233.140241007208;2256.616375216209;2270.5685919675207;2274.351809734226;2284.3946354216064;2241.4396400899777;2248.460369511322;2265.8753768542724;2321.3384185507134;2321.4776675248104;2203.008646109249;2204;2211.40292121128;2211.845170838042;2217.0355895185567;2210.8341874359326;2218;2221.555688862234;2226.677131815894;2230.509287956647;8437.620307086134;8471.449355791916;8494.277986371166;8496.150384961516;8571.742847714517;4974;5038.7984480620735;5042.142835717937;5057.544820966131;5098.6176036797015;3100.4884194107944;3120.719135277836;3292.5555050068174;3295.24209431832;3298.5546951161527;1083.8103331916882;1084.7342401111698;1091.6397588795758;1094.3105843318738;1118.1502058435567;11054.944725276402;11060.336379817156;11093.944530277378;11157.66527004183;11182.16133789969;4177.352510360899;4267.594578515036;4401.603855653007;4419.911601767946;4422.7346359218545;9470.289728270407;9505.334626576212;9538.666146684833;9540.856887146698;9547.713568592953;4988.650794444354;4990.8752281193265;5001.224810154432;5004.973980334023;5018.799248030111;4048.4534587830854;4413.933790993137;4553.248713962225;4588.449386073693;4649;3739.9813000935364;3788.431735239681;3789.3936970084656;3834.079820842978;3856.787876666754;1261.2909507617046;1262.19850394998;1264.3994102801096;1274.3628185907046;1295.2487557216791;959.031378307914;959.2853324273461;976.3848775271533;985.3792110970043;999.3354419311222;5510.752016159283;5678.375378708339;5693.686847223443;5712.086066229466;5727.678174104826;413.1983951134789;415.93760935859865;417.36143700138837;419.3939757051032;425.15818679015723;3989.3018721723583;4069.1861627674584;4157.563455837154;4178.498580170399;4260.318349064135;9735.367201131903;9935.751606209758;10000.14998725102;10049.59801607942;10059.647912323033;9944.60801437186;10007.49962501872;10009.249306302056;10012.098911098035;10054.798904021878;7744.728934487379;7776.533407995538;7805.063392392835;7846.411519136088;7863.292303692695;5724.685142317213;5762.2221000164445;5852.005159122874;5861.648301101947;5961.165436838933;9418.481983490974;9488.85766713486;9500.95249523748;9515.666951656936;9517.904820951702;9282.535873206178;9433.716988490356;9504.762380940532;9556.283278753983;9663.516824158905;7238.819029524304;7434.814129646802;7500.024996750388;7501.812454688677;7502.699892004259;8012.238837310446;8085.231902969209;8115.83768324642;8116.959415202886;8116.959415202886;7002.929970700227;7010.544314619533;7108.822279443055;7220.855582888208;7327.2672732726305;6839.965800170968;7261.092363454568;7396.371308438628;7637.389008879388;7661.310482056641;6746.055552222595;6769.966150169218;6817.079694241413;6929.896051559332;6931.133608298963;5087.669301495391;5111.182210846396;5184.585233181412;5186.6369354144845;5252.369715633996;5009.674371165863;5197.870053248699;5305.098133317422;5306.761195746123;5318.8936221276135 22:19:30 INFO - PROCESS | 4345 | |4;prototype.html;307.66618219232134;320.8684439379855;325.4776084384563;331.62691971532024;333.6546674192211;5454.618176727621;5491.780328786854;5648.435156484355;5660.886782264352;5665.631733937296;5536.861578460541;5574.358948720898;5587;5588.329400471939;5596.188552659862;4246.596573325529;4248.87253382399;4263.680223983198;4264.339027450735;4275.0594869128745;4095.283325418055;4207.663386929038;4252;4302.333138363559;4308.987387963826;4164.02208950749;4169.374593810922;4190.413342132104;4197.454330936974;4206.642435392995;4200.823769344588;4212.53662097169;4246.723962942429;4254;4260.723053001545;3593.2274560969518;3670.632936706308;3678.319510890494;3698.4267438547067;3705.5368078990127;5456.290682211287;5467.797084641373;5485.780568777244;5500.514860987511;5531.197976293455;4851.51484851512;4862.17343051682;4865.951340486586;4897.853064408074;4917.827876024322;5614.747336369873;5769.653820770767;5850.47887549236;5851.297844258674;5879.67661778602;4019.638232559084;4031.4154447604933;4040.7777572233517;4053.148838743867;4056.655184309337;6121.449069583714;6131.202943617302;6173.536984726164;6194.163787888668;6198.938010619881;4077.347624380144;4167.97916010418;4168.291390463629;4172.749635021908;4174.269502836992;4188.350805625072;4194.6853985951175;4222.239996800544;4223.852165174204;4238.703290769617;4825.6139508839915;4839.637027222902;4908.950910490921;4922.8769280768265;4942.258661200777;5765.1640512125505;5908.586398952118;5923.259592550931;5995.550333724988;6016.338202797689;1445.588007417902;1460.1531111955042;1465.2600436779358;1472.2565104622097;1473.528470889305;901.3555307954713;912.4798864647088;940.3558562384789;955.383777463546;957.042957042957;4984.8753781155765;4991.750412479361;5038.926254984456;5058.544730974157;5071.290019397287;5337.359516858002;5414.617568269949;5433.397147841396;5448.346198456211;5465.371482279509;5068.023470846373;5084.262781896604;5185.170372740419;5413.404525502192;5812.505936995402;3228.176814912182;3233.752541983299;3248.197250525981;3278.377108349406;3292.275699346188;5967.7919169906545;6223.502119830403;6249.6875156242;6298.559100862895;6315.273743519462;6106.1146133811235;6115.571909966348;6127.601705889103;6128.846778830565;6201.534884883562;6151.661658608819;6170.382961703794;6193.058297308742;6203.31763506014;6233.8779019776875;5865.560082993878;6026.397360263939;6063.696815159313;6068.332483426908;6084.452399284086;5971.462568368867;5983.880322393615;5992.580519363602;6046.7278972445465;6050.788222412194;6088.452039316483;6245.532299909609;6309.968450157719;6365.618062916054;6366;4091.2226676931286;4167.312393455046;4217.966598183465;4260.786960652017;4263.914721705486;6082.695865206633;6104.4200800923845;6106.069086838431;6121.938780612315;6138.299148374701;5780.104083867007;5864.501517371071;5870.412958704095;5976.252968378953;6044.370698746059;5824.970875145597;5880.26496687914;5887.293524777054;5888.438529239623;5923.318818335774;5715.685637289989;5725.427457254241;5756.395578464369;5840.1531777892815;5849.713063126105;5672.432756724294;5886.793962211302;5936.465718085393;5966.820995370146;5991.64050156992;5851.180834683063;5902.970485147718;5943.197668314722;5943.52451803846;5951.125395500577 22:19:30 INFO - PROCESS | 4345 | |5;yui.html;729.2525161709248;750.8948747175396;781.2265856801768;819.2954059508825;860.987085193722;14869.776953345696;16110.919445402757;16209.513714588566;16275.674486510263;16508.25712842922;14888.776668349956;15203.467878624251;15204;15224.391024359013;15241;655.931127231641;780.6018930345522;784.5841703896915;807.0839597057324;808.8382323535317;811.5577010529275;813.6745301879266;815.7511958852548;815.8327542853701;816.4488969945293;861.4960248254779;864.1617630898019;867.7440155154245;867.9479231246146;869.5478351257342;853.8633818588996;860.9698660546914;861.3884142258972;862.2326129744532;862.9223369896708;626.7524327890503;628.7516431009725;629.7669862150988;629.8173529676388;632.7026297640148;14834.406623735038;14852.18312992785;14889.627759306104;14910.70178596433;14976.700465990729;15099.924500377538;15145;15302.158381289024;15491.302891369804;15589.532314030597;12489.18820276679;12566.874331256662;12623.11898098813;12635.241885486901;12789.552365667158;3789.9242015159816;3793.165503589206;3806.7144964127524;3819.236152769457;3930.9017274568364;74667;74749;76890;78207.60896195483;78215.6089219556;11416.828747568794;11552.364619945816;11817.172797904064;11937.761244775229;12274.693132671577;22353;22486.775132248797;22525.54948901013;22528.211512597307;22553.4361640957;8238.340932725368;8408.957955210308;8464.941882264717;8507.914920850711;8528.317734581218;4520.615747661485;4636.327732478839;4674.368960190331;4692.671512994126;4725.692829966041;3459.8789042383396;3520.4191308434333;3528;3540.716742660581;3542.326957877995;616.4729156571157;632.3360471504901;644.4522156167315;645.4158986117571;653.7809833705762;602.62637164958;704.8625518023936;713.4684659928387;715.9642017899084;725.5900416264793;9217.493037882981;9223.799878266575;9375.859362109573;9395.342326037111;9431.764205894908;611.3825036712952;637.5505268785495;680.5304340005381;698.1866125963297;698.6157613312657;4038.273110840018;4316.719413238129;4328.718633288826;4393.824247030083;4399.886953546779;116921.41539292248;117356.26437356367;119973;123883;125073;91128;104543.47728261462;105482.47258763657;105493;107507.46246268718;58720.70639646774;73489;82923.58538207391;84754.57622711846;87173.56413217893;9305.767355816159;10158.847617285599;10208.489575521342;10242;10397.636082737068;51701.741491292305;52647.47352526426;53686.46313536815;54195.458045420615;54406.72796635992;74129.6293518529;74549;76242.61878690572;76999.61500192463;79409.20590794018;13010.869891301345;13232;13307.933460332637;13341;13347.199168049949;13596.320183990958;13805.51680691171;13930.930345348208;13967;14435.639109022359;13181.80227296572;13963.720725585634;13973;14073.296335183404;14118.858811412165;13256.79950657566;13521.188728675917;13868.861311386758;13913.860861391662;14798.48205312809;12006.75986480248;13830.585082447542;13842.4463021478;14184.219867907364;14842.925785371004;10368.53341599645;10788.946055269673;10888.455577221266;12048.879511205127;12428.75142497127;10373.481325933824;10513.632022879163;10751.946240268748;11130.109591232844;11367.772644547229 22:19:30 INFO - PROCESS | 4345 | __end_tp_report 22:19:30 INFO - PROCESS | 4345 | __start_cc_report 22:19:30 INFO - PROCESS | 4345 | _x_x_mozilla_cycle_collect,353 22:19:30 INFO - PROCESS | 4345 | __end_cc_report 22:19:30 INFO - PROCESS | 4345 | __startTimestamp1469596770316__endTimestamp 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | ------- Summary: start ------- 22:19:30 INFO - PROCESS | 4345 | Number of tests: 6 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | [#0] dojo.html Cycles:165 Average:5436.81 Median:5679.26 stddev:626.45 (11.0%) stddev-sans-first:600.32 22:19:30 INFO - PROCESS | 4345 | Values: 3073.7 3106.7 3110.1 3123.2 3138.9 5435.2 5458.9 5470.3 5547.3 5642.2 5309.7 5420.5 5473.6 5494.7 5573.6 4879.5 4907.4 5019.3 5057.9 5100.7 4902.4 5100.2 5101.0 5110.8 5113.4 4889.7 4949.5 4974.0 5025.8 5055.9 4978.4 4978.6 4998.6 5016.6 5030.4 4947.1 4948.7 4955.3 4964.3 5014.2 5363.8 5439.8 5451.4 5494.1 5494.9 5885.1 5908.4 5913.2 5915.4 5940.5 5822.2 5873.4 5882.3 5882.8 5903.7 5251.4 5412.2 5440.1 5452.7 5525.8 5991.4 5994.4 5996.6 6005.2 6033.6 5776.5 5780.3 5782.2 5784.8 5785.2 5704.0 5767.2 5796.3 5803.7 5838.0 5579.4 5780.7 5823.3 5839.2 5889.0 5835.5 5850.7 5871.8 5888.5 5898.9 4310.3 4359.8 4413.9 4459.4 4463.6 3899.7 3953.0 3965.1 3971.1 3976.9 5483.4 5525.0 5537.1 5550.1 5603.6 5710.6 5823.7 5866.9 5892.3 5899.6 5576.6 5600.8 5605.5 5627.2 5642.6 5036.7 5074.7 5076.8 5080.7 5113.9 5883.9 5921.2 6049.0 6053.9 6059.2 5796.9 5867.5 5876.3 5918.4 5918.9 5972.6 6000.2 6024.0 6027.9 6035.1 5566.6 5689.2 5768.9 5775.5 5788.2 5826.2 5859.2 5889.9 5929.2 5933.0 5723.3 5734.7 5740.1 5742.9 5746.9 5563.9 5702.0 5709.5 5714.5 5721.9 5670.5 5684.1 5687.2 5706.9 5721.5 5774.7 5797.2 5819.5 5825.0 5834.5 5461.2 5658.7 5669.2 5679.3 5706.1 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | [#1] ext.html Cycles:180 Average:65235.03 Median:44692.05 stddev:132371.66 (296.2%) stddev-sans-first:132653.90 22:19:30 INFO - PROCESS | 4345 | Values: 548.7 587.7 589.5 609.7 617.2 39799.4 44330.0 44515.0 44646.1 44738.0 44499.0 45061.0 45080.5 45135.3 45138.3 8817.3 8961.5 8970.8 8973.1 8981.1 8869.1 8978.9 8979.4 8985.3 8986.8 8903.9 8982.1 8992.3 9006.2 9017.6 8904.3 8909.6 8955.1 8956.6 8962.0 8824.6 8857.2 8880.6 8889.8 8894.4 42008.6 42403.6 42457.6 42527.0 42598.8 2950.1 3444.1 3454.8 3471.9 3580.7 33347.2 33371.5 33476.5 33701.5 34895.8 4460.4 4480.2 4492.3 4519.5 4578.7 344235.0 345134.3 345512.0 346350.0 348598.0 59877.4 60119.1 60128.1 60261.0 60340.7 36090.5 36126.0 36655.3 38446.0 38529.4 17786.8 17952.2 17988.0 18013.2 18048.1 13710.2 13837.2 13837.2 13840.0 13943.4 19568.2 19763.0 19840.7 19856.2 19991.9 7033.5 7112.3 7266.8 7347.8 7429.9 952.8 953.0 1058.5 1067.1 1109.7 31506.5 33047.3 33213.5 33238.8 33358.2 1102.8 1111.7 1114.7 1155.7 1191.3 16908.7 17208.9 17344.3 17353.1 17395.1 608201.0 612505.0 612926.0 621919.0 627199.0 61271.4 62126.4 62129.7 62137.4 62246.0 1332.0 1365.0 1365.0 1366.0 1382.2 30757.4 32349.8 32405.5 32664.3 33292.2 51447.5 61544.0 62782.0 62957.1 63257.7 490546.0 493216.0 493338.0 494942.0 495435.3 45099.5 45965.8 46062.3 46170.3 46653.8 50996.0 51158.2 51396.7 51797.7 52456.0 42640.1 43069.6 43165.1 43890.6 45767.5 43915.0 44246.3 44432.8 45073.5 45715.8 43635.0 44111.3 45329.8 45992.1 46077.1 39992.8 42188.0 42192.2 47107.8 48001.8 33392.0 35381.6 35539.0 35684.3 36086.3 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | [#2] jquery.html Cycles:180 Average:29541.07 Median:1356.99 stddev:83206.34 (6131.7%) stddev-sans-first:83410.95 22:19:30 INFO - PROCESS | 4345 | Values: 380.8 387.7 399.9 400.2 412.2 18506.0 19930.7 20088.2 20237.6 20616.0 18378.5 18734.9 18882.0 19468.9 19789.4 5053.0 5098.7 5256.1 5301.1 5350.6 5320.8 5377.2 5385.2 5387.7 5391.6 5292.2 5347.9 5348.1 5365.6 5394.0 5407.8 5414.3 5414.4 5421.0 5422.1 5228.1 5230.1 5282.8 5287.9 5302.6 13898.2 14056.3 14098.8 21530.5 23021.4 2170.8 2188.9 2253.6 2287.3 2333.1 10046.5 10251.1 10257.7 10447.9 10559.7 1890.1 1958.7 1967.2 2005.2 2009.5 82140.9 83473.6 84273.6 84319.0 84721.6 10543.3 11047.1 11118.1 11230.6 11490.1 15540.3 15558.3 15575.8 15588.1 15614.9 8176.6 8265.9 8273.0 8424.5 8549.2 7915.7 7973.4 8173.7 8190.7 8199.7 4588.2 4602.0 4668.5 4699.4 4790.3 1938.4 1957.3 1959.4 1992.8 2006.5 933.8 941.0 991.4 1014.9 1070.8 19668.1 19750.1 19857.9 20033.9 20056.0 949.2 965.6 981.4 990.7 1006.7 8260.3 10587.5 11028.6 11128.8 11143.0 477876.0 492446.0 505660.0 512632.0 528958.0 91688.5 92994.5 97289.0 99109.0 99734.5 969.2 1008.3 1025.7 1026.1 1033.8 16903.7 17149.5 17628.7 17756.2 18152.6 38846.6 43129.6 43983.3 44713.3 47019.5 60049.0 60400.0 60565.0 61769.7 62007.0 8424.2 8446.2 8629.1 8686.5 8694.9 12238.5 12370.6 12406.2 13246.2 13450.8 11639.9 11662.7 11680.6 11751.6 12178.9 11632.4 11696.2 11863.2 12019.6 12141.3 11729.6 11814.3 11842.5 11990.0 12243.4 8427.8 8721.7 8729.4 8855.1 8960.6 10643.6 11626.1 11782.9 11856.1 11918.9 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | [#3] mootools.html Cycles:180 Average:5492.30 Median:5049.84 stddev:3045.51 (60.3%) stddev-sans-first:3039.05 22:19:30 INFO - PROCESS | 4345 | Values: 1470.2 1493.5 1611.3 1798.1 1802.1 7217.3 8041.5 8115.7 8492.0 8516.7 8104.0 8404.7 8423.7 8458.2 8476.7 2271.5 2298.5 2311.2 2311.6 2311.6 2233.1 2256.6 2270.6 2274.4 2284.4 2241.4 2248.5 2265.9 2321.3 2321.5 2203.0 2204.0 2211.4 2211.8 2217.0 2210.8 2218.0 2221.6 2226.7 2230.5 8437.6 8471.4 8494.3 8496.2 8571.7 4974.0 5038.8 5042.1 5057.5 5098.6 3100.5 3120.7 3292.6 3295.2 3298.6 1083.8 1084.7 1091.6 1094.3 1118.2 11054.9 11060.3 11093.9 11157.7 11182.2 4177.4 4267.6 4401.6 4419.9 4422.7 9470.3 9505.3 9538.7 9540.9 9547.7 4988.7 4990.9 5001.2 5005.0 5018.8 4048.5 4413.9 4553.2 4588.4 4649.0 3740.0 3788.4 3789.4 3834.1 3856.8 1261.3 1262.2 1264.4 1274.4 1295.2 959.0 959.3 976.4 985.4 999.3 5510.8 5678.4 5693.7 5712.1 5727.7 413.2 415.9 417.4 419.4 425.2 3989.3 4069.2 4157.6 4178.5 4260.3 9735.4 9935.8 10000.1 10049.6 10059.6 9944.6 10007.5 10009.2 10012.1 10054.8 7744.7 7776.5 7805.1 7846.4 7863.3 5724.7 5762.2 5852.0 5861.6 5961.2 9418.5 9488.9 9501.0 9515.7 9517.9 9282.5 9433.7 9504.8 9556.3 9663.5 7238.8 7434.8 7500.0 7501.8 7502.7 8012.2 8085.2 8115.8 8117.0 8117.0 7002.9 7010.5 7108.8 7220.9 7327.3 6840.0 7261.1 7396.4 7637.4 7661.3 6746.1 6770.0 6817.1 6929.9 6931.1 5087.7 5111.2 5184.6 5186.6 5252.4 5009.7 5197.9 5305.1 5306.8 5318.9 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | [#4] prototype.html Cycles:180 Average:4876.41 Median:5534.03 stddev:1472.57 (26.6%) stddev-sans-first:1436.22 22:19:30 INFO - PROCESS | 4345 | Values: 307.7 320.9 325.5 331.6 333.7 5454.6 5491.8 5648.4 5660.9 5665.6 5536.9 5574.4 5587.0 5588.3 5596.2 4246.6 4248.9 4263.7 4264.3 4275.1 4095.3 4207.7 4252.0 4302.3 4309.0 4164.0 4169.4 4190.4 4197.5 4206.6 4200.8 4212.5 4246.7 4254.0 4260.7 3593.2 3670.6 3678.3 3698.4 3705.5 5456.3 5467.8 5485.8 5500.5 5531.2 4851.5 4862.2 4866.0 4897.9 4917.8 5614.7 5769.7 5850.5 5851.3 5879.7 4019.6 4031.4 4040.8 4053.1 4056.7 6121.4 6131.2 6173.5 6194.2 6198.9 4077.3 4168.0 4168.3 4172.7 4174.3 4188.4 4194.7 4222.2 4223.9 4238.7 4825.6 4839.6 4909.0 4922.9 4942.3 5765.2 5908.6 5923.3 5995.6 6016.3 1445.6 1460.2 1465.3 1472.3 1473.5 901.4 912.5 940.4 955.4 957.0 4984.9 4991.8 5038.9 5058.5 5071.3 5337.4 5414.6 5433.4 5448.3 5465.4 5068.0 5084.3 5185.2 5413.4 5812.5 3228.2 3233.8 3248.2 3278.4 3292.3 5967.8 6223.5 6249.7 6298.6 6315.3 6106.1 6115.6 6127.6 6128.8 6201.5 6151.7 6170.4 6193.1 6203.3 6233.9 5865.6 6026.4 6063.7 6068.3 6084.5 5971.5 5983.9 5992.6 6046.7 6050.8 6088.5 6245.5 6310.0 6365.6 6366.0 4091.2 4167.3 4218.0 4260.8 4263.9 6082.7 6104.4 6106.1 6121.9 6138.3 5780.1 5864.5 5870.4 5976.3 6044.4 5825.0 5880.3 5887.3 5888.4 5923.3 5715.7 5725.4 5756.4 5840.2 5849.7 5672.4 5886.8 5936.5 5966.8 5991.6 5851.2 5903.0 5943.2 5943.5 5951.1 22:19:30 INFO - PROCESS | 4345 | 22:19:30 INFO - PROCESS | 4345 | [#5] yui.html Cycles:180 Average:21026.03 Median:3812.98 stddev:30433.01 (798.1%) stddev-sans-first:30480.22 22:19:30 INFO - PROCESS | 4345 | Values: 729.3 750.9 781.2 819.3 861.0 14869.8 16110.9 16209.5 16275.7 16508.3 14888.8 15203.5 15204.0 15224.4 15241.0 655.9 780.6 784.6 807.1 808.8 811.6 813.7 815.8 815.8 816.4 861.5 864.2 867.7 867.9 869.5 853.9 861.0 861.4 862.2 862.9 626.8 628.8 629.8 629.8 632.7 14834.4 14852.2 14889.6 14910.7 14976.7 15099.9 15145.0 15302.2 15491.3 15589.5 12489.2 12566.9 12623.1 12635.2 12789.6 3789.9 3793.2 3806.7 3819.2 3930.9 74667.0 74749.0 76890.0 78207.6 78215.6 11416.8 11552.4 11817.2 11937.8 12274.7 22353.0 22486.8 22525.5 22528.2 22553.4 8238.3 8409.0 8464.9 8507.9 8528.3 4520.6 4636.3 4674.4 4692.7 4725.7 3459.9 3520.4 3528.0 3540.7 3542.3 616.5 632.3 644.5 645.4 653.8 602.6 704.9 713.5 716.0 725.6 9217.5 9223.8 9375.9 9395.3 9431.8 611.4 637.6 680.5 698.2 698.6 4038.3 4316.7 4328.7 4393.8 4399.9 116921.4 117356.3 119973.0 123883.0 125073.0 91128.0 104543.5 105482.5 105493.0 107507.5 58720.7 73489.0 82923.6 84754.6 87173.6 9305.8 10158.8 10208.5 10242.0 10397.6 51701.7 52647.5 53686.5 54195.5 54406.7 74129.6 74549.0 76242.6 76999.6 79409.2 13010.9 13232.0 13307.9 13341.0 13347.2 13596.3 13805.5 13930.9 13967.0 14435.6 13181.8 13963.7 13973.0 14073.3 14118.9 13256.8 13521.2 13868.9 13913.9 14798.5 12006.8 13830.6 13842.4 14184.2 14842.9 10368.5 10788.9 10888.5 12048.9 12428.8 10373.5 10513.6 10751.9 11130.1 11367.8 22:19:30 INFO - PROCESS | 4345 | -------- Summary: end -------- 22:19:30 INFO - PROCESS | 4345 | 22:19:31 INFO - TEST-INFO | 4345: exit 0 22:19:31 INFO - TEST-OK | dromaeo_css | took 1111273ms 22:19:31 INFO - TEST-START | kraken 22:19:31 INFO - operating with platform_type : linux_ 22:19:31 INFO - Initialising browser for kraken test... 22:19:31 INFO - TEST-INFO | started process 4600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiQE_o8/profile http://localhost:48496/getInfo.html) 22:19:34 INFO - PROCESS | 4600 | JavaScript error: , line 0: uncaught exception: undefined 22:19:35 INFO - PROCESS | 4600 | __metrics Screen width/height:1600/1200 22:19:35 INFO - PROCESS | 4600 | colorDepth:24 22:19:35 INFO - PROCESS | 4600 | Browser inner width/height: 1024/697 22:19:35 INFO - PROCESS | 4600 | __metrics 22:19:36 ERROR - PROCESS | 4600 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:19:37 INFO - TEST-INFO | 4600: exit 0 22:19:37 INFO - Browser initialized. 22:19:37 INFO - Running cycle 1/1 for kraken test... 22:19:37 INFO - TEST-INFO | started process 4723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiQE_o8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:19:48 INFO - PROCESS | 4723 | RSS: Main: 167981056 22:19:48 INFO - PROCESS | 4723 | 22:19:51 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48496/tests/kraken/driver.html?testName=audio-beat-detection) 22:19:52 INFO - PROCESS | 4723 | RSS: Main: 173252608 22:19:52 INFO - PROCESS | 4723 | 22:19:54 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48496/tests/kraken/driver.html?testName=audio-dft) 22:19:55 INFO - PROCESS | 4723 | RSS: Main: 172158976 22:19:55 INFO - PROCESS | 4723 | 22:19:58 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48496/tests/kraken/driver.html?testName=audio-fft) 22:19:59 INFO - PROCESS | 4723 | RSS: Main: 168316928 22:19:59 INFO - PROCESS | 4723 | 22:20:01 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48496/tests/kraken/driver.html?testName=audio-oscillator) 22:20:01 INFO - PROCESS | 4723 | RSS: Main: 169193472 22:20:01 INFO - PROCESS | 4723 | 22:20:03 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48496/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:04 INFO - PROCESS | 4723 | RSS: Main: 169447424 22:20:04 INFO - PROCESS | 4723 | 22:20:07 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48496/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:08 INFO - PROCESS | 4723 | RSS: Main: 169168896 22:20:08 INFO - PROCESS | 4723 | 22:20:11 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48496/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:12 INFO - PROCESS | 4723 | RSS: Main: 167174144 22:20:12 INFO - PROCESS | 4723 | 22:20:15 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48496/tests/kraken/driver.html?testName=json-parse-financial) 22:20:15 INFO - PROCESS | 4723 | RSS: Main: 168787968 22:20:15 INFO - PROCESS | 4723 | 22:20:17 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48496/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:20:17 INFO - PROCESS | 4723 | RSS: Main: 168804352 22:20:17 INFO - PROCESS | 4723 | 22:20:19 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:20:20 INFO - PROCESS | 4723 | RSS: Main: 169287680 22:20:20 INFO - PROCESS | 4723 | 22:20:22 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:20:23 INFO - PROCESS | 4723 | RSS: Main: 169558016 22:20:23 INFO - PROCESS | 4723 | 22:20:25 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:20:26 INFO - PROCESS | 4723 | RSS: Main: 169947136 22:20:26 INFO - PROCESS | 4723 | 22:20:29 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:20:29 INFO - PROCESS | 4723 | RSS: Main: 169574400 22:20:29 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | Cycle 1(1): loaded http://localhost:48496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48496/tests/kraken/driver.html?testName=ai-astar) 22:20:31 INFO - PROCESS | 4723 | RSS: Main: 170438656 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | __start_tp_report 22:20:31 INFO - PROCESS | 4723 | _x_x_mozilla_page_load 22:20:31 INFO - PROCESS | 4723 | _x_x_mozilla_page_load_details 22:20:31 INFO - PROCESS | 4723 | |i|pagename|runs| 22:20:31 INFO - PROCESS | 4723 | |0;ai-astar;103;114;105;112;112;112;114;111;113;113 22:20:31 INFO - PROCESS | 4723 | |1;audio-beat-detection;149;118;144;177;121;142;145;120;184;117 22:20:31 INFO - PROCESS | 4723 | |2;audio-dft;163;161;161;163;164;164;171;163;160;161 22:20:31 INFO - PROCESS | 4723 | |3;audio-fft;76;76;75;158;76;74;77;75;155;76 22:20:31 INFO - PROCESS | 4723 | |4;audio-oscillator;85;83;158;83;83;83;154;84;83;157 22:20:31 INFO - PROCESS | 4723 | |5;imaging-gaussian-blur;120;118;117;117;118;117;117;118;118;117 22:20:31 INFO - PROCESS | 4723 | |6;imaging-darkroom;147;147;154;147;146;147;146;146;146;146 22:20:31 INFO - PROCESS | 4723 | |7;imaging-desaturate;95;95;95;95;96;98;95;96;95;95 22:20:31 INFO - PROCESS | 4723 | |8;json-parse-financial;54;54;53;56;53;53;53;55;52;54 22:20:31 INFO - PROCESS | 4723 | |9;json-stringify-tinderbox;62;62;63;63;63;63;62;62;62;63 22:20:31 INFO - PROCESS | 4723 | |10;stanford-crypto-aes;76;86;76;77;76;75;82;78;75;75 22:20:31 INFO - PROCESS | 4723 | |11;stanford-crypto-ccm;124;128;123;123;121;124;124;127;201;131 22:20:31 INFO - PROCESS | 4723 | |12;stanford-crypto-pbkdf2;157;158;160;159;156;161;154;158;156;155 22:20:31 INFO - PROCESS | 4723 | |13;stanford-crypto-sha256-iterative;66;64;65;64;67;62;62;66;63;68 22:20:31 INFO - PROCESS | 4723 | __end_tp_report 22:20:31 INFO - PROCESS | 4723 | __start_cc_report 22:20:31 INFO - PROCESS | 4723 | _x_x_mozilla_cycle_collect,937 22:20:31 INFO - PROCESS | 4723 | __end_cc_report 22:20:31 INFO - PROCESS | 4723 | __startTimestamp1469596831635__endTimestamp 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | ------- Summary: start ------- 22:20:31 INFO - PROCESS | 4723 | Number of tests: 14 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.50 stddev:3.78 (3.4%) stddev-sans-first:2.73 22:20:31 INFO - PROCESS | 4723 | Values: 103.0 114.0 105.0 112.0 112.0 112.0 114.0 111.0 113.0 113.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:144.50 stddev:23.98 (16.6%) stddev-sans-first:25.29 22:20:31 INFO - PROCESS | 4723 | Values: 149.0 118.0 144.0 177.0 121.0 142.0 145.0 120.0 184.0 117.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:3.11 (1.9%) stddev-sans-first:3.30 22:20:31 INFO - PROCESS | 4723 | Values: 163.0 161.0 161.0 163.0 164.0 164.0 171.0 163.0 160.0 161.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#3] audio-fft Cycles:10 Average:91.80 Median:76.00 stddev:34.12 (44.9%) stddev-sans-first:35.70 22:20:31 INFO - PROCESS | 4723 | Values: 76.0 76.0 75.0 158.0 76.0 74.0 77.0 75.0 155.0 76.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#4] audio-oscillator Cycles:10 Average:105.30 Median:83.00 stddev:35.24 (42.5%) stddev-sans-first:36.60 22:20:31 INFO - PROCESS | 4723 | Values: 85.0 83.0 158.0 83.0 83.0 83.0 154.0 84.0 83.0 157.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.95 (0.8%) stddev-sans-first:0.53 22:20:31 INFO - PROCESS | 4723 | Values: 120.0 118.0 117.0 117.0 118.0 117.0 117.0 118.0 118.0 117.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:2.44 (1.7%) stddev-sans-first:2.59 22:20:31 INFO - PROCESS | 4723 | Values: 147.0 147.0 154.0 147.0 146.0 147.0 146.0 146.0 146.0 146.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 22:20:31 INFO - PROCESS | 4723 | Values: 95.0 95.0 95.0 95.0 96.0 98.0 95.0 96.0 95.0 95.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.16 (2.1%) stddev-sans-first:1.22 22:20:31 INFO - PROCESS | 4723 | Values: 54.0 54.0 53.0 56.0 53.0 53.0 53.0 55.0 52.0 54.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:63.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 22:20:31 INFO - PROCESS | 4723 | Values: 62.0 62.0 63.0 63.0 63.0 63.0 62.0 62.0 62.0 63.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:76.50 stddev:3.63 (4.7%) stddev-sans-first:3.80 22:20:31 INFO - PROCESS | 4723 | Values: 76.0 86.0 76.0 77.0 76.0 75.0 82.0 78.0 75.0 75.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:125.50 stddev:24.21 (19.3%) stddev-sans-first:25.48 22:20:31 INFO - PROCESS | 4723 | Values: 124.0 128.0 123.0 123.0 121.0 124.0 124.0 127.0 201.0 131.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.40 Median:158.00 stddev:2.22 (1.4%) stddev-sans-first:2.35 22:20:31 INFO - PROCESS | 4723 | Values: 157.0 158.0 160.0 159.0 156.0 161.0 154.0 158.0 156.0 155.0 22:20:31 INFO - PROCESS | 4723 | 22:20:31 INFO - PROCESS | 4723 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.50 stddev:2.06 (3.1%) stddev-sans-first:2.13 22:20:31 INFO - PROCESS | 4723 | Values: 66.0 64.0 65.0 64.0 67.0 62.0 62.0 66.0 63.0 68.0 22:20:31 INFO - PROCESS | 4723 | -------- Summary: end -------- 22:20:31 INFO - PROCESS | 4723 | 22:20:32 INFO - TEST-INFO | 4723: exit 0 22:20:32 INFO - TEST-OK | kraken | took 61619ms 22:20:32 INFO - SUITE-END | took 1172s 22:20:32 INFO - Completed test suite (00:19:33) 22:20:32 INFO - javascript benchmark 22:20:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7297.561488337521, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3073.6618971913094, 3106.7359274461655, 3110.0980715592473, 3123.187971127506, 3138.890138845141, 5435.157550579666, 5458.9454105458935, 5470.261514695515, 5547.33431988162, 5642.210090587321, 5309.707966061865, 5420.4850539198715, 5473.589480788937, 5494.725263736817, 5573.637713548616, 4879.53644403782, 4907.362042934414, 5019.272205530196, 5057.949420505803, 5100.668456550312, 4902.436219834711, 5100.15847385181, 5101.005303965728, 5110.821121260757, 5113.437521872591, 4889.682170658896, 4949.505049495057, 4974.030064137512, 5025.84922452327, 5055.873603159914, 4978.427480839714, 4978.576820970222, 4998.600111991032, 5016.598672106223, 5030.37120359955, 4947.134251505972, 4948.67833590819, 4955.306257124005, 4964.255361695738, 5014.172661510846, 5363.839084827461, 5439.836804895859, 5451.400345961941, 5494.066008778517, 5494.890102197974, 5885.087811389241, 5908.350081491032, 5913.172155898178, 5915.438033386821, 5940.495057920082, 5822.184894114827, 5873.353931067579, 5882.29412470502, 5882.823515294548, 5903.675297858616, 5251.4485978972425, 5412.215228791803, 5440.102383106782, 5452.672839629635, 5525.751341189656, 5991.370906054889, 5994.430529099728, 5996.610220335664, 6005.189299444519, 6033.637981721111, 5776.537876969833, 5780.277465316835, 5782.190493330937, 5784.8264552062665, 5785.218995435646, 5704.0, 5767.163761254595, 5796.275465566804, 5803.6807975560905, 5838.0, 5579.414161513064, 5780.6531608102805, 5823.272090988627, 5839.182514447981, 5888.970555147197, 5835.4748072673665, 5850.707464626752, 5871.7651293947765, 5888.5289176865745, 5898.911516327258, 4310.288802347577, 4359.757469121285, 4413.911721765547, 4459.375687403766, 4463.575960283768, 3899.6880249579967, 3952.960470395317, 3965.0682089708894, 3971.0866500705, 3976.940345894814, 5483.396826349099, 5524.977879092381, 5537.086380747212, 5550.139728342114, 5603.6077474577205, 5710.571707121982, 5823.679697616672, 5866.941330586725, 5892.292924849045, 5899.587028908021, 5576.609637325349, 5600.831975040755, 5605.495505404451, 5627.240322556402, 5642.633228840113, 5036.748162591855, 5074.67014644047, 5076.847694569169, 5080.669756465818, 5113.897722045538, 5883.941160588425, 5921.2302400688495, 6049.03215485529, 6053.878922421527, 6059.182010428536, 5796.8550786229835, 5867.501262392744, 5876.265466816648, 5918.378570250234, 5918.881622367442, 5972.641641501524, 6000.21997140369, 6024.0, 6027.879442411039, 6035.094735789667, 5566.582506311962, 5689.175069614966, 5768.884622307446, 5775.48020678141, 5788.160716696139, 5826.155207494805, 5859.179714840011, 5889.852753681021, 5929.16991621182, 5933.02105152645, 5723.3418156912485, 5734.684592347461, 5740.1102829061565, 5742.885142297214, 5746.913796293143, 5563.860903477445, 5702.002149623882, 5709.457601527848, 5714.542836572981, 5721.8569535761935, 5670.489655930986, 5684.118961560965, 5687.1753595729215, 5706.885862282648, 5721.456461636138, 5774.711264436677, 5797.246357973437, 5819.5344372450945, 5825.0388685866355, 5834.533237341087, 5461.15352120422, 5658.745356459051, 5669.177969194526, 5679.261695979496, 5706.115552089433], "value": 5393.381714255597, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.7377110622062, 587.6591576885406, 589.4724221821467, 609.6585911889342, 617.2099712368171, 39799.403008954854, 44330.0, 44515.0, 44646.107077858425, 44738.0, 44499.0, 45061.0, 45080.54919450804, 45135.322970155474, 45138.32292515615, 8817.250533704642, 8961.507117108556, 8970.775730606754, 8973.057828927966, 8981.056989016124, 8869.068747781486, 8978.86531702025, 8979.37144399889, 8985.281177505816, 8986.775330616754, 8903.866442003375, 8982.146696063863, 8992.325575581825, 9006.234470070052, 9017.639294428214, 8904.287656987426, 8909.643614255421, 8955.05971872957, 8956.641734330618, 8962.0, 8824.558772061371, 8857.202851743374, 8880.644774209024, 8889.822203555957, 8894.421862578976, 42008.579914200775, 42403.57596424027, 42457.57542424567, 42527.0, 42598.78700606508, 2950.1002194330936, 3444.121748954001, 3454.827258637058, 3471.9063494998995, 3580.6777390034763, 33347.16632084193, 33371.49942750861, 33476.49785253223, 33701.483433245565, 34895.82552087249, 4460.420145381112, 4480.238359478896, 4492.326151077365, 4519.525449827786, 4578.725276483422, 344235.0, 345134.27432862925, 345512.0, 346350.0, 348598.0, 59877.401225988055, 60119.098213526835, 60128.098078528856, 60261.0, 60340.698296509116, 36090.45864312037, 36126.0, 36655.26689466196, 38446.0, 38529.422058669144, 17786.822131778776, 17952.19215135322, 17988.0, 18013.18940647674, 18048.09759512019, 13710.24593647359, 13837.169769813843, 13837.169769813843, 13840.0, 13943.372548235353, 19568.217271309273, 19763.0, 19840.702389464168, 19856.205751770056, 19991.900040499997, 7033.4724895633035, 7112.324329188718, 7266.818329541804, 7347.7795666130105, 7429.888551671621, 952.7713348796377, 952.9666461673808, 1058.4972138234432, 1067.0503252105632, 1109.6504601050804, 31506.52740208899, 33047.3390532188, 33213.50179747306, 33238.833805830815, 33358.16604584905, 1102.7518808268235, 1111.6609434122508, 1114.7213196700825, 1155.6937411585936, 1191.2614179208772, 16908.661826763397, 17208.913955430395, 17344.30622775075, 17353.132343382782, 17395.130243488027, 608201.0, 612505.0, 612926.0, 621919.0, 627199.0, 61271.38728612746, 62126.37873621206, 62129.68935155295, 62137.37862621316, 62246.0, 1331.9600411987462, 1364.9727005459836, 1365.044468871774, 1366.0369439545098, 1382.198324971514, 30757.38485230238, 32349.838250808596, 32405.51391729173, 32664.34671306513, 33292.167695807795, 51447.48552514427, 61544.0, 62782.0, 62957.11128666147, 63257.68371158115, 490546.0, 493216.0, 493338.0, 494942.0, 495435.2738835681, 45099.549004509536, 45965.77017114893, 46062.30906536338, 46170.307445387676, 46653.76673116613, 50996.0, 51158.23262651137, 51396.74301628468, 51797.741011294704, 52456.0, 42640.14719705527, 43069.569304307806, 43165.136697266506, 43890.56109438992, 45767.54232457633, 43915.0, 44246.336304954806, 44432.777836111905, 45073.54926450693, 45715.77142114268, 43635.0, 44111.31710663058, 45329.773351134354, 45992.08015839598, 46077.078458429976, 39992.80003599964, 42188.0, 42192.1561568773, 47107.764461177474, 48001.75999119982, 33392.0, 35381.646183537836, 35539.0, 35684.28631427305, 36086.27827443489], "value": 21039.19967636814, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.83433706337763, 387.71115518938393, 399.8880313512217, 400.2155774681624, 412.2476480423226, 18506.0, 19930.70103948442, 20088.196472141117, 20237.59524809503, 20616.0, 18378.540536486595, 18734.906325468386, 18882.0, 19468.9026554867, 19789.40631781045, 5053.0399224147295, 5098.745062746848, 5256.106461901486, 5301.0988132017455, 5350.625456218067, 5320.787168513274, 5377.220303056055, 5385.21914322423, 5387.703676297802, 5391.56867450603, 5292.179712144604, 5347.866303342409, 5348.144296912495, 5365.570754339663, 5394.02907476654, 5407.810726624549, 5414.3232095988005, 5414.377346605132, 5420.972895135519, 5422.05114105032, 5228.08508511009, 5230.110881150214, 5282.788688452457, 5287.867803304948, 5302.602304827154, 13898.23559704216, 14056.297185140702, 14098.788518172236, 21530.492865499422, 23021.424464388358, 2170.7937745914114, 2188.872730543771, 2253.5830871288863, 2287.2795069553035, 2333.1484008337006, 10046.497675116216, 10251.128654064414, 10257.743556411075, 10447.947760261226, 10559.736006599896, 1890.1399863062275, 1958.6670266054805, 1967.1541237267968, 2005.1678553400322, 2009.4976255936017, 82140.94186346795, 83473.58263208705, 84273.57863210706, 84318.99999999939, 84721.57639211827, 10543.26197166191, 11047.116230701524, 11118.110551155889, 11230.55077796879, 11490.080793536497, 15540.300686469136, 15558.299876505584, 15575.844241557666, 15588.14265215424, 15614.921925390527, 8176.550289734003, 8265.876011859827, 8273.007239131342, 8424.4945303282, 8549.187827156411, 7915.722949696733, 7973.362131029619, 8173.713920012771, 8190.7133250335955, 8199.672013119527, 4588.242939914877, 4602.0, 4668.50980647034, 4699.412573428322, 4790.257510085942, 1938.4378530226077, 1957.2758079510672, 1959.4219705187004, 1992.7807941126464, 2006.4883454719245, 933.8085692433035, 940.9623615055322, 991.3803872579638, 1014.8832884218261, 1070.8340207267886, 19668.11493482768, 19750.11124499401, 19857.900710496357, 20033.899830500755, 20056.0, 949.188443880486, 965.6089283840056, 981.3768257156756, 990.7126933189294, 1006.6828948881079, 8260.339172866277, 10587.470626468647, 11028.558857645765, 11128.833067503994, 11143.0, 477876.0, 492446.0, 505660.0, 512632.0, 528958.0, 91688.54155729178, 92994.53502732443, 97289.0, 99109.0, 99734.50132749435, 969.2149359019216, 1008.2538921198407, 1025.6717850287837, 1026.0867827633413, 1033.813913495563, 16903.66192676164, 17149.485515434557, 17628.735568966218, 17756.200970956597, 18152.636947261246, 38846.6115338843, 43129.56870431256, 43983.340249895635, 44713.329300061174, 47019.529804702885, 60049.0, 60400.0, 60565.0, 61769.69115154396, 62007.0, 8424.157584241526, 8446.155384461505, 8629.09394513567, 8686.522241276538, 8694.91305086941, 12238.510459581517, 12370.587735391513, 12406.193597416139, 13246.20522768637, 13450.798238026242, 11639.88360116388, 11662.70843228926, 11680.649580512598, 11751.647450576496, 12178.939105304416, 11632.418379081182, 11696.239744416585, 11863.228890122115, 12019.579314723942, 12141.271523708252, 11729.589464368706, 11814.291142531476, 11842.52629894829, 11990.0, 12243.449044792827, 8427.789305267417, 8721.694740684046, 8729.388942773945, 8855.114488551351, 8960.641574337214, 10643.574257029632, 11626.069914406997, 11782.882171178178, 11856.11079169083, 11918.940405297919], "value": 9135.24743070276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.1767010474134, 1493.537003528906, 1611.2507683926979, 1798.100949525238, 1802.0629272778147, 7217.27827217278, 8041.51750894946, 8115.715949941753, 8491.957540212297, 8516.701915432961, 8103.959480202606, 8404.747857564284, 8423.663053477854, 8458.196471335228, 8476.745697629065, 2271.466205441724, 2298.4713515891353, 2311.1910831209025, 2311.56080344734, 2311.595470792613, 2233.140241007208, 2256.616375216209, 2270.5685919675207, 2274.351809734226, 2284.3946354216064, 2241.4396400899777, 2248.460369511322, 2265.8753768542724, 2321.3384185507134, 2321.4776675248104, 2203.008646109249, 2204.0, 2211.40292121128, 2211.845170838042, 2217.0355895185567, 2210.8341874359326, 2218.0, 2221.555688862234, 2226.677131815894, 2230.509287956647, 8437.620307086134, 8471.449355791916, 8494.277986371166, 8496.150384961516, 8571.742847714517, 4974.0, 5038.7984480620735, 5042.142835717937, 5057.544820966131, 5098.6176036797015, 3100.4884194107944, 3120.719135277836, 3292.5555050068174, 3295.24209431832, 3298.5546951161527, 1083.8103331916882, 1084.7342401111698, 1091.6397588795758, 1094.3105843318738, 1118.1502058435567, 11054.944725276402, 11060.336379817156, 11093.944530277378, 11157.66527004183, 11182.16133789969, 4177.352510360899, 4267.594578515036, 4401.603855653007, 4419.911601767946, 4422.7346359218545, 9470.289728270407, 9505.334626576212, 9538.666146684833, 9540.856887146698, 9547.713568592953, 4988.650794444354, 4990.8752281193265, 5001.224810154432, 5004.973980334023, 5018.799248030111, 4048.4534587830854, 4413.933790993137, 4553.248713962225, 4588.449386073693, 4649.0, 3739.9813000935364, 3788.431735239681, 3789.3936970084656, 3834.079820842978, 3856.787876666754, 1261.2909507617046, 1262.19850394998, 1264.3994102801096, 1274.3628185907046, 1295.2487557216791, 959.031378307914, 959.2853324273461, 976.3848775271533, 985.3792110970043, 999.3354419311222, 5510.752016159283, 5678.375378708339, 5693.686847223443, 5712.086066229466, 5727.678174104826, 413.1983951134789, 415.93760935859865, 417.36143700138837, 419.3939757051032, 425.15818679015723, 3989.3018721723583, 4069.1861627674584, 4157.563455837154, 4178.498580170399, 4260.318349064135, 9735.367201131903, 9935.751606209758, 10000.14998725102, 10049.59801607942, 10059.647912323033, 9944.60801437186, 10007.49962501872, 10009.249306302056, 10012.098911098035, 10054.798904021878, 7744.728934487379, 7776.533407995538, 7805.063392392835, 7846.411519136088, 7863.292303692695, 5724.685142317213, 5762.2221000164445, 5852.005159122874, 5861.648301101947, 5961.165436838933, 9418.481983490974, 9488.85766713486, 9500.95249523748, 9515.666951656936, 9517.904820951702, 9282.535873206178, 9433.716988490356, 9504.762380940532, 9556.283278753983, 9663.516824158905, 7238.819029524304, 7434.814129646802, 7500.024996750388, 7501.812454688677, 7502.699892004259, 8012.238837310446, 8085.231902969209, 8115.83768324642, 8116.959415202886, 8116.959415202886, 7002.929970700227, 7010.544314619533, 7108.822279443055, 7220.855582888208, 7327.2672732726305, 6839.965800170968, 7261.092363454568, 7396.371308438628, 7637.389008879388, 7661.310482056641, 6746.055552222595, 6769.966150169218, 6817.079694241413, 6929.896051559332, 6931.133608298963, 5087.669301495391, 5111.182210846396, 5184.585233181412, 5186.6369354144845, 5252.369715633996, 5009.674371165863, 5197.870053248699, 5305.098133317422, 5306.761195746123, 5318.8936221276135], "value": 4365.18916360645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.66618219232134, 320.8684439379855, 325.4776084384563, 331.62691971532024, 333.6546674192211, 5454.618176727621, 5491.780328786854, 5648.435156484355, 5660.886782264352, 5665.631733937296, 5536.861578460541, 5574.358948720898, 5587.0, 5588.329400471939, 5596.188552659862, 4246.596573325529, 4248.87253382399, 4263.680223983198, 4264.339027450735, 4275.0594869128745, 4095.283325418055, 4207.663386929038, 4252.0, 4302.333138363559, 4308.987387963826, 4164.02208950749, 4169.374593810922, 4190.413342132104, 4197.454330936974, 4206.642435392995, 4200.823769344588, 4212.53662097169, 4246.723962942429, 4254.0, 4260.723053001545, 3593.2274560969518, 3670.632936706308, 3678.319510890494, 3698.4267438547067, 3705.5368078990127, 5456.290682211287, 5467.797084641373, 5485.780568777244, 5500.514860987511, 5531.197976293455, 4851.51484851512, 4862.17343051682, 4865.951340486586, 4897.853064408074, 4917.827876024322, 5614.747336369873, 5769.653820770767, 5850.47887549236, 5851.297844258674, 5879.67661778602, 4019.638232559084, 4031.4154447604933, 4040.7777572233517, 4053.148838743867, 4056.655184309337, 6121.449069583714, 6131.202943617302, 6173.536984726164, 6194.163787888668, 6198.938010619881, 4077.347624380144, 4167.97916010418, 4168.291390463629, 4172.749635021908, 4174.269502836992, 4188.350805625072, 4194.6853985951175, 4222.239996800544, 4223.852165174204, 4238.703290769617, 4825.6139508839915, 4839.637027222902, 4908.950910490921, 4922.8769280768265, 4942.258661200777, 5765.1640512125505, 5908.586398952118, 5923.259592550931, 5995.550333724988, 6016.338202797689, 1445.588007417902, 1460.1531111955042, 1465.2600436779358, 1472.2565104622097, 1473.528470889305, 901.3555307954713, 912.4798864647088, 940.3558562384789, 955.383777463546, 957.042957042957, 4984.8753781155765, 4991.750412479361, 5038.926254984456, 5058.544730974157, 5071.290019397287, 5337.359516858002, 5414.617568269949, 5433.397147841396, 5448.346198456211, 5465.371482279509, 5068.023470846373, 5084.262781896604, 5185.170372740419, 5413.404525502192, 5812.505936995402, 3228.176814912182, 3233.752541983299, 3248.197250525981, 3278.377108349406, 3292.275699346188, 5967.7919169906545, 6223.502119830403, 6249.6875156242, 6298.559100862895, 6315.273743519462, 6106.1146133811235, 6115.571909966348, 6127.601705889103, 6128.846778830565, 6201.534884883562, 6151.661658608819, 6170.382961703794, 6193.058297308742, 6203.31763506014, 6233.8779019776875, 5865.560082993878, 6026.397360263939, 6063.696815159313, 6068.332483426908, 6084.452399284086, 5971.462568368867, 5983.880322393615, 5992.580519363602, 6046.7278972445465, 6050.788222412194, 6088.452039316483, 6245.532299909609, 6309.968450157719, 6365.618062916054, 6366.0, 4091.2226676931286, 4167.312393455046, 4217.966598183465, 4260.786960652017, 4263.914721705486, 6082.695865206633, 6104.4200800923845, 6106.069086838431, 6121.938780612315, 6138.299148374701, 5780.104083867007, 5864.501517371071, 5870.412958704095, 5976.252968378953, 6044.370698746059, 5824.970875145597, 5880.26496687914, 5887.293524777054, 5888.438529239623, 5923.318818335774, 5715.685637289989, 5725.427457254241, 5756.395578464369, 5840.1531777892815, 5849.713063126105, 5672.432756724294, 5886.793962211302, 5936.465718085393, 5966.820995370146, 5991.64050156992, 5851.180834683063, 5902.970485147718, 5943.197668314722, 5943.52451803846, 5951.125395500577], "value": 4398.181145417674, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.2525161709248, 750.8948747175396, 781.2265856801768, 819.2954059508825, 860.987085193722, 14869.776953345696, 16110.919445402757, 16209.513714588566, 16275.674486510263, 16508.25712842922, 14888.776668349956, 15203.467878624251, 15204.0, 15224.391024359013, 15241.0, 655.931127231641, 780.6018930345522, 784.5841703896915, 807.0839597057324, 808.8382323535317, 811.5577010529275, 813.6745301879266, 815.7511958852548, 815.8327542853701, 816.4488969945293, 861.4960248254779, 864.1617630898019, 867.7440155154245, 867.9479231246146, 869.5478351257342, 853.8633818588996, 860.9698660546914, 861.3884142258972, 862.2326129744532, 862.9223369896708, 626.7524327890503, 628.7516431009725, 629.7669862150988, 629.8173529676388, 632.7026297640148, 14834.406623735038, 14852.18312992785, 14889.627759306104, 14910.70178596433, 14976.700465990729, 15099.924500377538, 15145.0, 15302.158381289024, 15491.302891369804, 15589.532314030597, 12489.18820276679, 12566.874331256662, 12623.11898098813, 12635.241885486901, 12789.552365667158, 3789.9242015159816, 3793.165503589206, 3806.7144964127524, 3819.236152769457, 3930.9017274568364, 74667.0, 74749.0, 76890.0, 78207.60896195483, 78215.6089219556, 11416.828747568794, 11552.364619945816, 11817.172797904064, 11937.761244775229, 12274.693132671577, 22353.0, 22486.775132248797, 22525.54948901013, 22528.211512597307, 22553.4361640957, 8238.340932725368, 8408.957955210308, 8464.941882264717, 8507.914920850711, 8528.317734581218, 4520.615747661485, 4636.327732478839, 4674.368960190331, 4692.671512994126, 4725.692829966041, 3459.8789042383396, 3520.4191308434333, 3528.0, 3540.716742660581, 3542.326957877995, 616.4729156571157, 632.3360471504901, 644.4522156167315, 645.4158986117571, 653.7809833705762, 602.62637164958, 704.8625518023936, 713.4684659928387, 715.9642017899084, 725.5900416264793, 9217.493037882981, 9223.799878266575, 9375.859362109573, 9395.342326037111, 9431.764205894908, 611.3825036712952, 637.5505268785495, 680.5304340005381, 698.1866125963297, 698.6157613312657, 4038.273110840018, 4316.719413238129, 4328.718633288826, 4393.824247030083, 4399.886953546779, 116921.41539292248, 117356.26437356367, 119973.0, 123883.0, 125073.0, 91128.0, 104543.47728261462, 105482.47258763657, 105493.0, 107507.46246268718, 58720.70639646774, 73489.0, 82923.58538207391, 84754.57622711846, 87173.56413217893, 9305.767355816159, 10158.847617285599, 10208.489575521342, 10242.0, 10397.636082737068, 51701.741491292305, 52647.47352526426, 53686.46313536815, 54195.458045420615, 54406.72796635992, 74129.6293518529, 74549.0, 76242.61878690572, 76999.61500192463, 79409.20590794018, 13010.869891301345, 13232.0, 13307.933460332637, 13341.0, 13347.199168049949, 13596.320183990958, 13805.51680691171, 13930.930345348208, 13967.0, 14435.639109022359, 13181.80227296572, 13963.720725585634, 13973.0, 14073.296335183404, 14118.858811412165, 13256.79950657566, 13521.188728675917, 13868.861311386758, 13913.860861391662, 14798.48205312809, 12006.75986480248, 13830.585082447542, 13842.4463021478, 14184.219867907364, 14842.925785371004, 10368.53341599645, 10788.946055269673, 10888.455577221266, 12048.879511205127, 12428.75142497127, 10373.481325933824, 10513.632022879163, 10751.946240268748, 11130.109591232844, 11367.772644547229], "value": 7588.092565353934, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 105.0, 112.0, 112.0, 112.0, 114.0, 111.0, 113.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 144.0, 177.0, 121.0, 142.0, 145.0, 120.0, 184.0, 117.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 163.0, 164.0, 164.0, 171.0, 163.0, 160.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 158.0, 76.0, 74.0, 77.0, 75.0, 155.0, 76.0], "value": 91.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 158.0, 83.0, 83.0, 83.0, 154.0, 84.0, 83.0, 157.0], "value": 105.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 154.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 98.0, 95.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 56.0, 53.0, 53.0, 53.0, 55.0, 52.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 86.0, 76.0, 77.0, 76.0, 75.0, 82.0, 78.0, 75.0, 75.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 123.0, 123.0, 121.0, 124.0, 124.0, 127.0, 201.0, 131.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 160.0, 159.0, 156.0, 161.0, 154.0, 158.0, 156.0, 155.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 67.0, 62.0, 62.0, 66.0, 63.0, 68.0], "value": 64.7, "unit": "score"}]}]} 22:20:32 INFO - Return code: 0 22:20:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:20:32 ERROR - # TBPL SUCCESS # 22:20:32 INFO - Running post-action listener: _resource_record_post_action 22:20:32 INFO - [mozharness: 2016-07-27 05:20:32.934729Z] Finished run-tests step (success) 22:20:32 INFO - Running post-run listener: _resource_record_post_run 22:20:33 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 303341568; Read time: 16; Write time: 175300 22:20:33 INFO - TinderboxPrint: CPU usage
13.3% 22:20:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:20:33 INFO - TinderboxPrint: I/O write bytes / time
303,341,568 / 175,300 22:20:33 INFO - TinderboxPrint: CPU idle
8,232.9 (86.7%) 22:20:33 INFO - TinderboxPrint: CPU user
1,208.7 (12.7%) 22:20:33 INFO - TinderboxPrint: Swap in / out
0 / 0 22:20:33 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 8146944; Read time: 0; Write time: 472 22:20:33 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 295194624; Read time: 0; Write time: 174828 22:20:33 INFO - Running post-run listener: _upload_blobber_files 22:20:33 INFO - Blob upload gear active. 22:20:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:20:33 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 22:20:33 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'] 22:20:33 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 22:20:33 INFO - (blobuploader) - INFO - Open directory for files ... 22:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:43 INFO - (blobuploader) - INFO - Done attempting. 22:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:44 INFO - (blobuploader) - INFO - Done attempting. 22:20:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:45 INFO - (blobuploader) - INFO - Done attempting. 22:20:45 INFO - (blobuploader) - INFO - Iteration through files over. 22:20:45 INFO - Return code: 0 22:20:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:20:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:20:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03f836b139e4ea0f2a849c28d6c5a9f9e1fb798a045e61873373d33cffca8a77a1ded9f48f0544754aa230a40217679e618c5284a797e1c3cc961f4345b51fd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d3c5e2945454243a47648d63490fa82dfe9b7271e29940ed7e28f484a1249ada01de19d332312824ff54956236079f04a114f3fc4d03cbaf236a8c7ebe6e6a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/185883e0a5083e7e50170e1e1aa51f67b63ca9fd3830008b46851bc575f7c0df244264943d6dd7017eace416b901266832a602f21f5528219605fa0243661193"} 22:20:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:20:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:20:45 INFO - Contents: 22:20:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03f836b139e4ea0f2a849c28d6c5a9f9e1fb798a045e61873373d33cffca8a77a1ded9f48f0544754aa230a40217679e618c5284a797e1c3cc961f4345b51fd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d3c5e2945454243a47648d63490fa82dfe9b7271e29940ed7e28f484a1249ada01de19d332312824ff54956236079f04a114f3fc4d03cbaf236a8c7ebe6e6a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/185883e0a5083e7e50170e1e1aa51f67b63ca9fd3830008b46851bc575f7c0df244264943d6dd7017eace416b901266832a602f21f5528219605fa0243661193"} 22:20:45 INFO - Running post-run listener: copy_logs_to_upload_dir 22:20:45 INFO - Copying logs to upload dir... 22:20:45 INFO - mkdir: /builds/slave/test/build/upload/logs 22:20:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.696961 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-26 22:20:45.259456) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:20:45.262686) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03f836b139e4ea0f2a849c28d6c5a9f9e1fb798a045e61873373d33cffca8a77a1ded9f48f0544754aa230a40217679e618c5284a797e1c3cc961f4345b51fd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d3c5e2945454243a47648d63490fa82dfe9b7271e29940ed7e28f484a1249ada01de19d332312824ff54956236079f04a114f3fc4d03cbaf236a8c7ebe6e6a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/185883e0a5083e7e50170e1e1aa51f67b63ca9fd3830008b46851bc575f7c0df244264943d6dd7017eace416b901266832a602f21f5528219605fa0243661193"} build_url:https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/Qu4I-9htR7WZkv2UIhpOug/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03f836b139e4ea0f2a849c28d6c5a9f9e1fb798a045e61873373d33cffca8a77a1ded9f48f0544754aa230a40217679e618c5284a797e1c3cc961f4345b51fd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d3c5e2945454243a47648d63490fa82dfe9b7271e29940ed7e28f484a1249ada01de19d332312824ff54956236079f04a114f3fc4d03cbaf236a8c7ebe6e6a75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/185883e0a5083e7e50170e1e1aa51f67b63ca9fd3830008b46851bc575f7c0df244264943d6dd7017eace416b901266832a602f21f5528219605fa0243661193"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:20:45.291922) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:20:45.292290) ========= 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=03627c79f6bb3d1578c4a402000001ba-1469594290.669269-722829349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:20:45.376570) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:20:45.376943) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:20:45.377276) ========= ========= Total master_lag: 0.21 =========