builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1469184358.61 results: success (0) buildid: 20160722025914 builduid: 5f4790783b384259937c3bc73e748e7d revision: e0bc88708ffed39aaab1fbc0ac461d93561195de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.614370) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.614826) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.615101) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.629656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.629936) ========= 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=836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 03:45:59-- 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.7M=0s 2016-07-22 03:45:59 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075052 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.719396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.719741) ========= 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=836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018236 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.774873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:58.775182) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e0bc88708ffed39aaab1fbc0ac461d93561195de --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e0bc88708ffed39aaab1fbc0ac461d93561195de --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=836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 03:45:59,677 truncating revision to first 12 chars 2016-07-22 03:45:59,678 Setting DEBUG logging. 2016-07-22 03:45:59,678 attempt 1/10 2016-07-22 03:45:59,678 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e0bc88708ffe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 03:45:59,991 unpacking tar archive at: mozilla-central-e0bc88708ffe/testing/mozharness/ program finished with exit code 0 elapsedTime=0.710243 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:59.498580) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:59.498923) ========= script_repo_revision: e0bc88708ffed39aaab1fbc0ac461d93561195de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:59.499326) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:59.499608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 03:45:59.507917) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-22 03:45:59.508247) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:00 INFO - MultiFileLogger online at 20160722 03:46:00 in /builds/slave/test 03:46:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:46:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:00 INFO - {'append_to_log': False, 03:46:00 INFO - 'base_work_dir': '/builds/slave/test', 03:46:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:46:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:00 INFO - 'branch': 'Firefox-Non-PGO', 03:46:00 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:00 INFO - 'config_files': ('talos/linux_config.py',), 03:46:00 INFO - 'default_actions': ('clobber', 03:46:00 INFO - 'read-buildbot-config', 03:46:00 INFO - 'download-and-extract', 03:46:00 INFO - 'populate-webroot', 03:46:00 INFO - 'create-virtualenv', 03:46:00 INFO - 'install', 03:46:00 INFO - 'run-tests'), 03:46:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:00 INFO - 'download_minidump_stackwalk': True, 03:46:00 INFO - 'download_symbols': 'ondemand', 03:46:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:46:00 INFO - 'tooltool.py': '/tools/tooltool.py', 03:46:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:46:00 INFO - '/tools/misc-python/virtualenv.py')}, 03:46:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:00 INFO - 'installer_path': 'installer.exe', 03:46:00 INFO - 'log_level': 'info', 03:46:00 INFO - 'log_name': 'talos', 03:46:00 INFO - 'log_to_console': True, 03:46:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:00 INFO - 'opt_config_files': (), 03:46:00 INFO - 'pip_index': False, 03:46:00 INFO - 'sps_profile': False, 03:46:00 INFO - 'sps_profile_interval': 0, 03:46:00 INFO - 'suite': 'dromaeojs', 03:46:00 INFO - 'system_bits': '32', 03:46:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:00 INFO - 'title': 'talos-linux64-ix-028', 03:46:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:00 INFO - 'use_talos_json': True, 03:46:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:00 INFO - 'work_dir': 'build'} 03:46:00 INFO - [mozharness: 2016-07-22 10:46:00.506991Z] Running clobber step. 03:46:00 INFO - Running pre-action listener: _resource_record_pre_action 03:46:00 INFO - Running main action method: clobber 03:46:00 INFO - rmtree: /builds/slave/test/build 03:46:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:01 INFO - Running post-action listener: _resource_record_post_action 03:46:01 INFO - [mozharness: 2016-07-22 10:46:01.499568Z] Finished clobber step (success) 03:46:01 INFO - [mozharness: 2016-07-22 10:46:01.499669Z] Running read-buildbot-config step. 03:46:01 INFO - Running pre-action listener: _resource_record_pre_action 03:46:01 INFO - Running main action method: read_buildbot_config 03:46:01 INFO - Using buildbot properties: 03:46:01 INFO - { 03:46:01 INFO - "project": "", 03:46:01 INFO - "product": "firefox", 03:46:01 INFO - "script_repo_revision": "production", 03:46:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 03:46:01 INFO - "repository": "", 03:46:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 03:46:01 INFO - "buildid": "20160722025914", 03:46:01 INFO - "pgo_build": "False", 03:46:01 INFO - "basedir": "/builds/slave/test", 03:46:01 INFO - "buildnumber": 659, 03:46:01 INFO - "slavename": "talos-linux64-ix-028", 03:46:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:46:01 INFO - "platform": "ubuntu64_hw", 03:46:01 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 03:46:01 INFO - "branch": "mozilla-central", 03:46:01 INFO - "repo_path": "mozilla-central", 03:46:01 INFO - "revision": "e0bc88708ffed39aaab1fbc0ac461d93561195de", 03:46:01 INFO - "stage_platform": "linux64", 03:46:01 INFO - "builduid": "5f4790783b384259937c3bc73e748e7d", 03:46:01 INFO - "slavebuilddir": "test" 03:46:01 INFO - } 03:46:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 03:46:01 INFO - Running post-action listener: _resource_record_post_action 03:46:01 INFO - [mozharness: 2016-07-22 10:46:01.502112Z] Finished read-buildbot-config step (success) 03:46:01 INFO - [mozharness: 2016-07-22 10:46:01.502201Z] Running download-and-extract step. 03:46:01 INFO - Running pre-action listener: _resource_record_pre_action 03:46:01 INFO - Running main action method: download_and_extract 03:46:01 INFO - mkdir: /builds/slave/test/build/tests 03:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:01 INFO - https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:46:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:46:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:46:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:03 INFO - Downloaded 1361 bytes. 03:46:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:46:03 INFO - Using the following test package requirements: 03:46:03 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:46:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:46:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:46:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'jsshell-linux-x86_64.zip'], 03:46:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:46:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:46:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:46:03 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:46:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:46:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:46:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:46:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:46:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:03 INFO - https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:46:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:46:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:04 INFO - Downloaded 22737530 bytes. 03:46:04 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'] 03:46:04 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 03:46:05 INFO - Return code: 0 03:46:05 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 03:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:05 INFO - https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:46:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:46:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:09 INFO - Downloaded 22737530 bytes. 03:46: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'] 03:46: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 03:46:11 INFO - Return code: 0 03:46:11 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')]} 03:46:11 INFO - https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:46:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:46:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:46:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:12 INFO - Downloaded 14403289 bytes. 03:46:12 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'] 03:46:12 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 03:46:12 INFO - Return code: 0 03:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:12 INFO - https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:46:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:46:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:46:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SwJ6LmwQQsqpA-97QYenHQ/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 03:46:18 INFO - Downloaded 57161863 bytes. 03:46:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:46:18 INFO - mkdir: /builds/slave/test/properties 03:46:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:46:18 INFO - Writing to file /builds/slave/test/properties/build_url 03:46:18 INFO - Contents: 03:46:18 INFO - build_url:https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:46:19 INFO - Running post-action listener: _resource_record_post_action 03:46:19 INFO - Running post-action listener: set_extra_try_arguments 03:46:19 INFO - [mozharness: 2016-07-22 10:46:19.713916Z] Finished download-and-extract step (success) 03:46:19 INFO - [mozharness: 2016-07-22 10:46:19.714187Z] Running populate-webroot step. 03:46:19 INFO - Running pre-action listener: _resource_record_pre_action 03:46:19 INFO - Running main action method: populate_webroot 03:46:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:46:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:46:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:46:19 INFO - '--tppagecycles', 03:46:19 INFO - '7'], 03:46:19 INFO - 'tests': ['tsvgm']}}, 03:46:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:46:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:46:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:46:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:46:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['damp', 'tps']}, 03:46:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'tests': ['damp', 'tps']}, 03:46:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['dromaeo_dom']}, 03:46:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:46:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['basic_compositor_video']}, 03:46:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:46:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['a11yr', 03:46:19 INFO - 'ts_paint', 03:46:19 INFO - 'tpaint', 03:46:19 INFO - 'sessionrestore', 03:46:19 INFO - 'sessionrestore_no_auto_restore', 03:46:19 INFO - 'tabpaint']}, 03:46:19 INFO - 'other-e10s': {'tests': ['a11yr', 03:46:19 INFO - 'ts_paint', 03:46:19 INFO - 'tpaint', 03:46:19 INFO - 'sessionrestore', 03:46:19 INFO - 'sessionrestore_no_auto_restore', 03:46:19 INFO - 'tabpaint']}, 03:46:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:46:19 INFO - 'ts_paint', 03:46:19 INFO - 'tpaint', 03:46:19 INFO - 'sessionrestore', 03:46:19 INFO - 'sessionrestore_no_auto_restore', 03:46:19 INFO - 'tabpaint']}, 03:46:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:46:19 INFO - 'ts_paint', 03:46:19 INFO - 'tpaint', 03:46:19 INFO - 'sessionrestore', 03:46:19 INFO - 'sessionrestore_no_auto_restore', 03:46:19 INFO - 'tabpaint']}, 03:46:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['a11yr', 03:46:19 INFO - 'ts_paint', 03:46:19 INFO - 'tpaint', 03:46:19 INFO - 'sessionrestore', 03:46:19 INFO - 'sessionrestore_no_auto_restore', 03:46:19 INFO - 'tabpaint']}, 03:46:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['a11yr', 03:46:19 INFO - 'ts_paint', 03:46:19 INFO - 'tpaint', 03:46:19 INFO - 'sessionrestore', 03:46:19 INFO - 'sessionrestore_no_auto_restore', 03:46:19 INFO - 'tabpaint']}, 03:46:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['tsvgx', 03:46:19 INFO - 'tsvgr_opacity', 03:46:19 INFO - 'tart', 03:46:19 INFO - 'tscrollx', 03:46:19 INFO - 'cart']}, 03:46:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:46:19 INFO - 'tsvgr_opacity', 03:46:19 INFO - 'tart', 03:46:19 INFO - 'tscrollx', 03:46:19 INFO - 'cart']}, 03:46:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'talos_options': ['--disable-e10s'], 03:46:19 INFO - 'tests': ['tp5o']}, 03:46:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'tests': ['tp5o']}, 03:46:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'talos_options': ['--disable-e10s', 03:46:19 INFO - '--xperf_path', 03:46:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:19 INFO - 'tests': ['tp5n']}, 03:46:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:19 INFO - 'talos_options': ['--xperf_path', 03:46:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:19 INFO - 'tests': ['tp5n']}}, 03:46:19 INFO - 'talos.zip': {'path': '', 03:46:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:46:19 INFO - Running post-action listener: _resource_record_post_action 03:46:19 INFO - [mozharness: 2016-07-22 10:46:19.729474Z] Finished populate-webroot step (success) 03:46:19 INFO - [mozharness: 2016-07-22 10:46:19.729563Z] Running create-virtualenv step. 03:46:19 INFO - Running pre-action listener: _resource_record_pre_action 03:46:19 INFO - Running main action method: create_virtualenv 03:46:19 INFO - Creating virtualenv /builds/slave/test/build/venv 03:46:19 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 03:46:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:46:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:46:19 INFO - Using real prefix '/usr' 03:46:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:46:20 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:22 INFO - Installing pip.................done. 03:46:22 INFO - Return code: 0 03:46:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46: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 03:46: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 03:46:22 INFO - Using env: {'DISPLAY': ':0', 03:46:22 INFO - 'HOME': '/home/cltbld', 03:46:22 INFO - 'LANG': 'en_US.UTF-8', 03:46:22 INFO - 'LANGUAGE': 'en_US:en', 03:46:22 INFO - 'LOGNAME': 'cltbld', 03:46:22 INFO - 'MAIL': '/var/mail/cltbld', 03:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:22 INFO - 'MOZ_NO_REMOTE': '1', 03:46:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:22 INFO - 'NO_EM_RESTART': '1', 03:46:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:22 INFO - 'PWD': '/builds/slave/test', 03:46:22 INFO - 'SHELL': '/bin/bash', 03:46:22 INFO - 'SHLVL': '1', 03:46:22 INFO - 'TERM': 'linux', 03:46:22 INFO - 'TMOUT': '86400', 03:46:22 INFO - 'USER': 'cltbld', 03:46:22 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:22 INFO - '_': '/tools/buildbot/bin/python'} 03:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46: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 03:46:22 INFO - Cleaning up... 03:46:22 INFO - Return code: 0 03:46:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46: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 03:46: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 03:46:22 INFO - Using env: {'DISPLAY': ':0', 03:46:22 INFO - 'HOME': '/home/cltbld', 03:46:22 INFO - 'LANG': 'en_US.UTF-8', 03:46:22 INFO - 'LANGUAGE': 'en_US:en', 03:46:22 INFO - 'LOGNAME': 'cltbld', 03:46:22 INFO - 'MAIL': '/var/mail/cltbld', 03:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:22 INFO - 'MOZ_NO_REMOTE': '1', 03:46:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:22 INFO - 'NO_EM_RESTART': '1', 03:46:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:22 INFO - 'PWD': '/builds/slave/test', 03:46:22 INFO - 'SHELL': '/bin/bash', 03:46:22 INFO - 'SHLVL': '1', 03:46:22 INFO - 'TERM': 'linux', 03:46:22 INFO - 'TMOUT': '86400', 03:46:22 INFO - 'USER': 'cltbld', 03:46:22 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:22 INFO - '_': '/tools/buildbot/bin/python'} 03:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:22 INFO - Downloading/unpacking psutil>=3.1.1 03:46:22 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 03:46:22 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 03:46:22 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 03:46:22 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 03:46:22 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 03:46:22 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 03:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:24 INFO - Installing collected packages: psutil 03:46:24 INFO - Running setup.py install for psutil 03:46:24 INFO - building 'psutil._psutil_linux' extension 03:46:24 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 03:46: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 03:46:25 INFO - building 'psutil._psutil_posix' extension 03:46: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 03:46: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 03:46:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:25 INFO - Successfully installed psutil 03:46:25 INFO - Cleaning up... 03:46:25 INFO - Return code: 0 03:46:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46: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 03:46: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 03:46:25 INFO - Using env: {'DISPLAY': ':0', 03:46:25 INFO - 'HOME': '/home/cltbld', 03:46:25 INFO - 'LANG': 'en_US.UTF-8', 03:46:25 INFO - 'LANGUAGE': 'en_US:en', 03:46:25 INFO - 'LOGNAME': 'cltbld', 03:46:25 INFO - 'MAIL': '/var/mail/cltbld', 03:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:25 INFO - 'MOZ_NO_REMOTE': '1', 03:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:25 INFO - 'NO_EM_RESTART': '1', 03:46:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:25 INFO - 'PWD': '/builds/slave/test', 03:46:25 INFO - 'SHELL': '/bin/bash', 03:46:25 INFO - 'SHLVL': '1', 03:46:25 INFO - 'TERM': 'linux', 03:46:25 INFO - 'TMOUT': '86400', 03:46:25 INFO - 'USER': 'cltbld', 03:46:25 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:25 INFO - '_': '/tools/buildbot/bin/python'} 03:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:46:25 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 03:46:25 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 03:46:25 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 03:46:25 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 03:46:25 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 03:46:25 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 03:46:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:46:27 INFO - Installing collected packages: mozsystemmonitor 03:46:27 INFO - Running setup.py install for mozsystemmonitor 03:46:27 INFO - Successfully installed mozsystemmonitor 03:46:27 INFO - Cleaning up... 03:46:27 INFO - Return code: 0 03:46:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:27 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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46:27 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 03:46:27 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 03:46:27 INFO - Using env: {'DISPLAY': ':0', 03:46:27 INFO - 'HOME': '/home/cltbld', 03:46:27 INFO - 'LANG': 'en_US.UTF-8', 03:46:27 INFO - 'LANGUAGE': 'en_US:en', 03:46:27 INFO - 'LOGNAME': 'cltbld', 03:46:27 INFO - 'MAIL': '/var/mail/cltbld', 03:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:27 INFO - 'MOZ_NO_REMOTE': '1', 03:46:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:27 INFO - 'NO_EM_RESTART': '1', 03:46:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:27 INFO - 'PWD': '/builds/slave/test', 03:46:27 INFO - 'SHELL': '/bin/bash', 03:46:27 INFO - 'SHLVL': '1', 03:46:27 INFO - 'TERM': 'linux', 03:46:27 INFO - 'TMOUT': '86400', 03:46:27 INFO - 'USER': 'cltbld', 03:46:27 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:27 INFO - '_': '/tools/buildbot/bin/python'} 03:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:27 INFO - Downloading/unpacking jsonschema==2.5.1 03:46:27 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 03:46:27 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 03:46:27 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 03:46:27 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 03:46:27 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 03:46:27 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 03:46:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:46:29 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 03:46:29 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 03:46:29 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 03:46:29 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 03:46:29 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 03:46:29 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 03:46:29 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:30 INFO - warning: no files found matching '*.txt' 03:46:30 INFO - no previously-included directories found matching 'build' 03:46:30 INFO - no previously-included directories found matching 'dist' 03:46:30 INFO - no previously-included directories found matching '.git*' 03:46:30 INFO - Installing collected packages: jsonschema, functools32 03:46:30 INFO - Running setup.py install for functools32 03:46:30 INFO - warning: no files found matching '*.txt' 03:46:30 INFO - no previously-included directories found matching 'build' 03:46:30 INFO - no previously-included directories found matching 'dist' 03:46:30 INFO - no previously-included directories found matching '.git*' 03:46:30 INFO - Successfully installed jsonschema functools32 03:46:30 INFO - Cleaning up... 03:46:30 INFO - Return code: 0 03:46:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46: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 03:46: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 03:46:30 INFO - Using env: {'DISPLAY': ':0', 03:46:30 INFO - 'HOME': '/home/cltbld', 03:46:30 INFO - 'LANG': 'en_US.UTF-8', 03:46:30 INFO - 'LANGUAGE': 'en_US:en', 03:46:30 INFO - 'LOGNAME': 'cltbld', 03:46:30 INFO - 'MAIL': '/var/mail/cltbld', 03:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:30 INFO - 'MOZ_NO_REMOTE': '1', 03:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:30 INFO - 'NO_EM_RESTART': '1', 03:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:30 INFO - 'PWD': '/builds/slave/test', 03:46:30 INFO - 'SHELL': '/bin/bash', 03:46:30 INFO - 'SHLVL': '1', 03:46:30 INFO - 'TERM': 'linux', 03:46:30 INFO - 'TMOUT': '86400', 03:46:30 INFO - 'USER': 'cltbld', 03:46:30 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:30 INFO - '_': '/tools/buildbot/bin/python'} 03:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:30 INFO - Downloading/unpacking blobuploader==1.2.4 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:33 INFO - Downloading docopt-0.6.1.tar.gz 03:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:33 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:33 INFO - Running setup.py install for blobuploader 03:46:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:33 INFO - Running setup.py install for requests 03:46:33 INFO - Running setup.py install for docopt 03:46:33 INFO - Successfully installed blobuploader requests docopt 03:46:33 INFO - Cleaning up... 03:46:33 INFO - Return code: 0 03:46:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:33 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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46:33 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 03:46:33 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 03:46:33 INFO - Using env: {'DISPLAY': ':0', 03:46:33 INFO - 'HOME': '/home/cltbld', 03:46:33 INFO - 'LANG': 'en_US.UTF-8', 03:46:33 INFO - 'LANGUAGE': 'en_US:en', 03:46:33 INFO - 'LOGNAME': 'cltbld', 03:46:33 INFO - 'MAIL': '/var/mail/cltbld', 03:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:33 INFO - 'MOZ_NO_REMOTE': '1', 03:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:33 INFO - 'NO_EM_RESTART': '1', 03:46:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:33 INFO - 'PWD': '/builds/slave/test', 03:46:33 INFO - 'SHELL': '/bin/bash', 03:46:33 INFO - 'SHLVL': '1', 03:46:33 INFO - 'TERM': 'linux', 03:46:33 INFO - 'TMOUT': '86400', 03:46:33 INFO - 'USER': 'cltbld', 03:46:33 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:33 INFO - '_': '/tools/buildbot/bin/python'} 03:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:33 INFO - Running setup.py (path:/tmp/pip-8InExc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:34 INFO - Running setup.py (path:/tmp/pip-5WIUAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:34 INFO - Running setup.py (path:/tmp/pip-mwM7FL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:34 INFO - Running setup.py (path:/tmp/pip-psmrLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:34 INFO - Running setup.py (path:/tmp/pip-HLG6l4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:34 INFO - Running setup.py (path:/tmp/pip-_MRNhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:34 INFO - Running setup.py (path:/tmp/pip-TkVVX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:34 INFO - Running setup.py (path:/tmp/pip-7yIOzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:34 INFO - Running setup.py (path:/tmp/pip-3dRKSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:34 INFO - Running setup.py (path:/tmp/pip-BKStz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:34 INFO - Running setup.py (path:/tmp/pip-GfbWtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:34 INFO - Running setup.py (path:/tmp/pip-I99Q31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:34 INFO - Running setup.py (path:/tmp/pip-iNqBeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:34 INFO - Running setup.py (path:/tmp/pip-vNh13v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:34 INFO - Running setup.py (path:/tmp/pip-v4SX3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:35 INFO - Running setup.py (path:/tmp/pip-7VK5xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:35 INFO - Running setup.py (path:/tmp/pip-lxtWy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:46:35 INFO - Running setup.py install for manifestparser 03:46:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:46:35 INFO - Running setup.py install for mozcrash 03:46:35 INFO - Running setup.py install for mozdebug 03:46:35 INFO - Running setup.py install for mozdevice 03:46:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:46:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:46:35 INFO - Running setup.py install for mozfile 03:46:35 INFO - Running setup.py install for mozhttpd 03:46:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:46:35 INFO - Running setup.py install for mozinfo 03:46:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:46:35 INFO - Running setup.py install for mozInstall 03:46:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Running setup.py install for mozleak 03:46:36 INFO - Running setup.py install for mozlog 03:46:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Running setup.py install for moznetwork 03:46:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Running setup.py install for mozprocess 03:46:36 INFO - Running setup.py install for mozprofile 03:46:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Running setup.py install for mozrunner 03:46:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Running setup.py install for mozscreenshot 03:46:36 INFO - Running setup.py install for moztest 03:46:36 INFO - Running setup.py install for mozversion 03:46:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:46:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:46:36 INFO - Cleaning up... 03:46:37 INFO - Return code: 0 03:46:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46: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 03:46: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 03:46:37 INFO - Using env: {'DISPLAY': ':0', 03:46:37 INFO - 'HOME': '/home/cltbld', 03:46:37 INFO - 'LANG': 'en_US.UTF-8', 03:46:37 INFO - 'LANGUAGE': 'en_US:en', 03:46:37 INFO - 'LOGNAME': 'cltbld', 03:46:37 INFO - 'MAIL': '/var/mail/cltbld', 03:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:37 INFO - 'MOZ_NO_REMOTE': '1', 03:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:37 INFO - 'NO_EM_RESTART': '1', 03:46:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:37 INFO - 'PWD': '/builds/slave/test', 03:46:37 INFO - 'SHELL': '/bin/bash', 03:46:37 INFO - 'SHLVL': '1', 03:46:37 INFO - 'TERM': 'linux', 03:46:37 INFO - 'TMOUT': '86400', 03:46:37 INFO - 'USER': 'cltbld', 03:46:37 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:37 INFO - '_': '/tools/buildbot/bin/python'} 03:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:37 INFO - Running setup.py (path:/tmp/pip-qAdXFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46: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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:37 INFO - Running setup.py (path:/tmp/pip-hzHNQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:37 INFO - Running setup.py (path:/tmp/pip-K_fIgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:37 INFO - Running setup.py (path:/tmp/pip-YQ_Nwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:37 INFO - Running setup.py (path:/tmp/pip-azncA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:37 INFO - Running setup.py (path:/tmp/pip-XjWDVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:37 INFO - Running setup.py (path:/tmp/pip-XMfh9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:37 INFO - Running setup.py (path:/tmp/pip-k7PrIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:37 INFO - Running setup.py (path:/tmp/pip-YNVPCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:37 INFO - Running setup.py (path:/tmp/pip-uOljQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:37 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)) 03:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:37 INFO - Running setup.py (path:/tmp/pip-dW5xXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46: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)) 03:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:38 INFO - Running setup.py (path:/tmp/pip-WdrcHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46: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)) 03:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:38 INFO - Running setup.py (path:/tmp/pip-NZVjXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46: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)) 03:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:38 INFO - Running setup.py (path:/tmp/pip-L54ZCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46: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)) 03:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:38 INFO - Running setup.py (path:/tmp/pip-fQAsUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:38 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)) 03:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:38 INFO - Running setup.py (path:/tmp/pip-LYJi1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:38 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)) 03:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:38 INFO - Running setup.py (path:/tmp/pip-VfCLDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:38 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)) 03:46:38 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)) 03:46:38 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)) 03:46:38 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)) 03:46:38 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)) 03:46:38 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)) 03:46:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:38 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 03:46:38 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 03:46:38 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 03:46:38 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 03:46:38 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 03:46:38 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 03:46:40 INFO - Downloading blessings-1.6.tar.gz 03:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:46:40 INFO - Installing collected packages: blessings 03:46:40 INFO - Running setup.py install for blessings 03:46:40 INFO - Successfully installed blessings 03:46:40 INFO - Cleaning up... 03:46:40 INFO - Return code: 0 03:46:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:40 INFO - Reading from file tmpfile_stdout 03:46:40 INFO - Current package versions: 03:46:40 INFO - argparse == 1.2.1 03:46:40 INFO - blessings == 1.6 03:46:40 INFO - blobuploader == 1.2.4 03:46:40 INFO - docopt == 0.6.1 03:46:40 INFO - functools32 == 3.2.3-2 03:46:40 INFO - jsonschema == 2.5.1 03:46:40 INFO - manifestparser == 1.1 03:46:40 INFO - mozInstall == 1.12 03:46:40 INFO - mozcrash == 0.17 03:46:40 INFO - mozdebug == 0.1 03:46:40 INFO - mozdevice == 0.48 03:46:40 INFO - mozfile == 1.2 03:46:40 INFO - mozhttpd == 0.7 03:46:40 INFO - mozinfo == 0.9 03:46:40 INFO - mozleak == 0.1 03:46:40 INFO - mozlog == 3.3 03:46:40 INFO - moznetwork == 0.27 03:46:40 INFO - mozprocess == 0.23 03:46:40 INFO - mozprofile == 0.28 03:46:40 INFO - mozrunner == 6.12 03:46:40 INFO - mozscreenshot == 0.1 03:46:40 INFO - mozsystemmonitor == 0.3 03:46:40 INFO - moztest == 0.7 03:46:40 INFO - mozversion == 1.4 03:46:40 INFO - psutil == 3.1.1 03:46:40 INFO - requests == 1.2.3 03:46:40 INFO - wsgiref == 0.1.2 03:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:40 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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46:40 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 03:46:40 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 03:46:40 INFO - Using env: {'DISPLAY': ':0', 03:46:40 INFO - 'HOME': '/home/cltbld', 03:46:40 INFO - 'LANG': 'en_US.UTF-8', 03:46:40 INFO - 'LANGUAGE': 'en_US:en', 03:46:40 INFO - 'LOGNAME': 'cltbld', 03:46:40 INFO - 'MAIL': '/var/mail/cltbld', 03:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:40 INFO - 'MOZ_NO_REMOTE': '1', 03:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:40 INFO - 'NO_EM_RESTART': '1', 03:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:40 INFO - 'PWD': '/builds/slave/test', 03:46:40 INFO - 'SHELL': '/bin/bash', 03:46:40 INFO - 'SHLVL': '1', 03:46:40 INFO - 'TERM': 'linux', 03:46:40 INFO - 'TMOUT': '86400', 03:46:40 INFO - 'USER': 'cltbld', 03:46:40 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:40 INFO - '_': '/tools/buildbot/bin/python'} 03:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46: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)) 03:46:41 INFO - Cleaning up... 03:46:41 INFO - Return code: 0 03:46:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x27c4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a85180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, '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': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', '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 03:46: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 03:46: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 03:46:41 INFO - Using env: {'DISPLAY': ':0', 03:46:41 INFO - 'HOME': '/home/cltbld', 03:46:41 INFO - 'LANG': 'en_US.UTF-8', 03:46:41 INFO - 'LANGUAGE': 'en_US:en', 03:46:41 INFO - 'LOGNAME': 'cltbld', 03:46:41 INFO - 'MAIL': '/var/mail/cltbld', 03:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:41 INFO - 'MOZ_NO_REMOTE': '1', 03:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:41 INFO - 'NO_EM_RESTART': '1', 03:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:41 INFO - 'PWD': '/builds/slave/test', 03:46:41 INFO - 'SHELL': '/bin/bash', 03:46:41 INFO - 'SHLVL': '1', 03:46:41 INFO - 'TERM': 'linux', 03:46:41 INFO - 'TMOUT': '86400', 03:46:41 INFO - 'USER': 'cltbld', 03:46:41 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:41 INFO - '_': '/tools/buildbot/bin/python'} 03:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:46:41 INFO - Cleaning up... 03:46:41 INFO - Return code: 0 03:46:41 INFO - Running post-action listener: _resource_record_post_action 03:46:41 INFO - Running post-action listener: _start_resource_monitoring 03:46:41 INFO - Starting resource monitoring. 03:46:41 INFO - [mozharness: 2016-07-22 10:46:41.344490Z] Finished create-virtualenv step (success) 03:46:41 INFO - [mozharness: 2016-07-22 10:46:41.344927Z] Running install step. 03:46:41 INFO - Running pre-action listener: _resource_record_pre_action 03:46:41 INFO - Running main action method: install 03:46:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:41 INFO - Reading from file tmpfile_stdout 03:46:41 INFO - Detecting whether we're running mozinstall >=1.0... 03:46:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:46:41 INFO - Reading from file tmpfile_stdout 03:46:41 INFO - Output received: 03:46:41 INFO - Usage: mozinstall [options] installer 03:46:41 INFO - Options: 03:46:41 INFO - -h, --help show this help message and exit 03:46:41 INFO - -d DEST, --destination=DEST 03:46:41 INFO - Directory to install application into. [default: 03:46:41 INFO - "/builds/slave/test"] 03:46:41 INFO - --app=APP Application being installed. [default: firefox] 03:46:41 INFO - mkdir: /builds/slave/test/build/application 03:46:41 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'] 03:46:41 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 03:46:58 INFO - Reading from file tmpfile_stdout 03:46:58 INFO - Output received: 03:46:58 INFO - /builds/slave/test/build/application/firefox/firefox 03:46:58 INFO - Running post-action listener: _resource_record_post_action 03:46:58 INFO - [mozharness: 2016-07-22 10:46:58.010618Z] Finished install step (success) 03:46:58 INFO - [mozharness: 2016-07-22 10:46:58.010897Z] Running run-tests step. 03:46:58 INFO - Running pre-action listener: _resource_record_pre_action 03:46:58 INFO - Running main action method: run_tests 03:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:46:58 INFO - Python 2.7.3 03:46:58 INFO - Return code: 0 03:46:58 INFO - grabbing minidump binary from tooltool 03:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:58 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 0x2a833b0>, '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 0x2a842b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a84740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:46:58 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 03:46:58 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 03:46:58 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 03:46:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:46:58 INFO - Return code: 0 03:46:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:46:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:46:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:46:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:46:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:46:58 INFO - Using env: {'DISPLAY': ':0', 03:46:58 INFO - 'HOME': '/home/cltbld', 03:46:58 INFO - 'LANG': 'en_US.UTF-8', 03:46:58 INFO - 'LANGUAGE': 'en_US:en', 03:46:58 INFO - 'LOGNAME': 'cltbld', 03:46:58 INFO - 'MAIL': '/var/mail/cltbld', 03:46:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:46:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:58 INFO - 'MOZ_NO_REMOTE': '1', 03:46:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:58 INFO - 'NO_EM_RESTART': '1', 03:46:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:58 INFO - 'PWD': '/builds/slave/test', 03:46:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:46:58 INFO - 'SHELL': '/bin/bash', 03:46:58 INFO - 'SHLVL': '1', 03:46:58 INFO - 'TERM': 'linux', 03:46:58 INFO - 'TMOUT': '86400', 03:46:58 INFO - 'USER': 'cltbld', 03:46:58 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152', 03:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:58 INFO - '_': '/tools/buildbot/bin/python'} 03:46:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:46:58 INFO - mozversion application_buildid: 20160722025914 03:46:58 INFO - mozversion application_changeset: e0bc88708ffed39aaab1fbc0ac461d93561195de 03:46:58 INFO - mozversion application_display_name: Nightly 03:46:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:46:58 INFO - mozversion application_name: Firefox 03:46:58 INFO - mozversion application_remotingname: firefox 03:46:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:46:58 INFO - mozversion application_vendor: Mozilla 03:46:58 INFO - mozversion application_version: 50.0a1 03:46:58 INFO - mozversion platform_buildid: 20160722025914 03:46:58 INFO - mozversion platform_changeset: e0bc88708ffed39aaab1fbc0ac461d93561195de 03:46:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:46:58 INFO - mozversion platform_version: 50.0a1 03:46:58 INFO - using testdate: 1469184418 03:46:58 INFO - actual date: 1469184418 03:46:58 INFO - starting webserver on 'localhost:35816' 03:46:58 INFO - SUITE-START | Running 2 tests 03:46:58 INFO - TEST-START | dromaeo_css 03:46:58 INFO - operating with platform_type : linux_ 03:46:58 INFO - Initialising browser for dromaeo_css test... 03:46:58 INFO - TEST-INFO | started process 12360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP9MwBb/profile http://localhost:35816/getInfo.html) 03:47:01 INFO - PROCESS | 12360 | JavaScript error: , line 0: uncaught exception: undefined 03:47:02 INFO - PROCESS | 12360 | __metrics Screen width/height:1600/1200 03:47:02 INFO - PROCESS | 12360 | colorDepth:24 03:47:02 INFO - PROCESS | 12360 | Browser inner width/height: 1024/697 03:47:02 INFO - PROCESS | 12360 | __metrics 03:47:03 INFO - PROCESS | 12360 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:47:03 INFO - PROCESS | 12360 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:47:04 INFO - PROCESS | 12360 | console.error: PushService: 03:47:04 INFO - PROCESS | 12360 | stateChangeProcessEnqueue: Error transitioning state 03:47:04 INFO - PROCESS | 12360 | UnknownError 03:47:04 INFO - TEST-INFO | 12360: exit 0 03:47:04 INFO - Browser initialized. 03:47:04 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:04 INFO - TEST-INFO | started process 12442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP9MwBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:16 INFO - PROCESS | 12442 | RSS: Main: 159043584 03:47:16 INFO - PROCESS | 12442 | 03:48:07 INFO - PROCESS | 12442 | 1469184487366 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] 03:50:20 INFO - PROCESS | 12442 | Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35816/tests/dromaeo/cssquery-ext.html) 03:50:21 INFO - PROCESS | 12442 | RSS: Main: 231870464 03:50:21 INFO - PROCESS | 12442 | 03:53:24 INFO - PROCESS | 12442 | Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-ext.html (next: http://localhost:35816/tests/dromaeo/cssquery-jquery.html) 03:53:25 INFO - PROCESS | 12442 | RSS: Main: 248852480 03:53:25 INFO - PROCESS | 12442 | 03:56:27 INFO - PROCESS | 12442 | Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35816/tests/dromaeo/cssquery-mootools.html) 03:56:27 INFO - PROCESS | 12442 | RSS: Main: 190562304 03:56:27 INFO - PROCESS | 12442 | 03:59:30 INFO - PROCESS | 12442 | Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35816/tests/dromaeo/cssquery-prototype.html) 03:59:30 INFO - PROCESS | 12442 | RSS: Main: 220340224 03:59:30 INFO - PROCESS | 12442 | 04:02:33 INFO - PROCESS | 12442 | Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35816/tests/dromaeo/cssquery-yui.html) 04:02:34 INFO - PROCESS | 12442 | RSS: Main: 191782912 04:02:34 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | Cycle 1(1): loaded http://localhost:35816/tests/dromaeo/cssquery-yui.html (next: http://localhost:35816/tests/dromaeo/cssquery-dojo.html) 04:05:37 INFO - PROCESS | 12442 | RSS: Main: 282353664 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | __start_tp_report 04:05:37 INFO - PROCESS | 12442 | _x_x_mozilla_page_load 04:05:37 INFO - PROCESS | 12442 | _x_x_mozilla_page_load_details 04:05:37 INFO - PROCESS | 12442 | |i|pagename|runs| 04:05:37 INFO - PROCESS | 12442 | |0;dojo.html;2574.214864466337;2575.420530380665;2609.93475163121;2617.5550156473396;2620.9606855897164;4534.909301813962;4633.467151277604;4671.112488627158;4727.8345257915935;4731.621470282378;4660.766961651921;4679.906401871961;4689.624830013599;4699.2951057341415;4707.529247075291;4283.828646854122;4355.629771469429;4356.6514678825615;4365.083332500179;4366.672499562545;4327.545607711192;4362.454693163355;4376.584224498668;4411.933820992687;4448.97775511122;4428;4473.038296766194;4478.104379124172;4489;4489.169503641837;4178.997040710238;4202.684798640114;4395.406620106277;4429.756363400012;4441.2671909135115;4224.007358270804;4225.302825033866;4271.273883439822;4278.122984788142;4279.807408666617;4615.399998000272;4634.073185362907;4640.396748422717;4649.930251046203;4708.95291047092;4933.555979961785;4983.053219888209;5015.122353588144;5024.196128619404;5029.723365214912;4848.369711937426;4910.901781964377;4979.352684151074;4987.850364489072;5057.190849464105;4686.039361930796;4724.834630787906;4725.64557658177;4728.810847566127;4729.55069268419;5029.069622119846;5166.767495462713;5172.844814655567;5174.094533456705;5179.870503237374;4900.093482705711;4918.827841025619;4936.2102063669645;5005.199168133081;5015.899682006412;5049.924251136236;5057.494250574987;5057.620678449131;5098.923516147261;5112.309838171874;4821.445533763592;4992.226204938241;5003.899922001539;5011.699298042129;5057.671251368649;4852.51474852519;4912.803487860517;4919.581835544019;4925.359703238556;4929.556339929423;4225.809838557272;4255.808488617958;4265.104328091135;4267.018585725301;4269;3711.9814400928362;3773.1699026214187;3775.867844625425;3778.074371778932;3778.3954567269107;4259.87220383389;4269.7224680395675;4306.913861722748;4313.61177494027;4328.329108988112;5020.623453241022;5029.270755740397;5049.5707864831165;5054.671446355975;5076.898462030738;4108.897277568085;4153.7300075494995;4281.550437204049;4285.721428107226;4324.308110702273;4481.260592002348;4496.752678602643;4519.502854685982;4535.659825513034;4542.227821270392;4624.421947256593;4645.744484053274;4647.34937108805;4650.790714417927;4692.0381321828945;4863.8540843774745;5011.248312753116;5026.899462010812;5029.9245511318095;5062.265971434195;4536.6824322297125;4627.282771170474;4662.020975595163;4664.556867097756;4721.055788842176;4967.254911763264;4970.478099799469;4983.426905905722;4983.6760610560195;5012.548870601663;5020.347354843847;5034.144195486703;5042.722650254272;5047.924281135859;5095.082885080721;4689;4806.615470762261;4841.782119804688;4848.10310092634;4922.655414120977;4870.95129048705;4877.244027175793;4878.878028049327;4883.243097319921;4908.558229759196;4767.69010014348;4784.832530861403;4828.058528586892;4832.130216561053;4842.080004799077;4742.976285118552;4869.366982292279;4889.9022019560125;4910.950890491049;4998.500149984972;4801.063792560417;4831.323614693875;4831.396075490564;4841.2496063110275;4874.147024270809 04:05:37 INFO - PROCESS | 12442 | |1;ext.html;566.2893069198157;583.1981026089128;590.2002786224665;593.285091464785;601.1283638723852;27058.32354191144;40189.196216075696;41775.164496710124;41814.79092604537;45055;40695.18609627813;41166.58833411665;41181.38227926583;41216.175676486455;41236.38145427813;7521.511101778394;7626;7641.350485208736;7645.044369453818;7675.577843218621;7640.5797681127515;7734.1492435832015;7734.458587898849;7739.5356278623185;7739.7678069658;7708.845823083563;7754.108277548098;7758.6896524138965;7763.728269510569;7777.455578109534;7689.269519395649;7694.461387702863;7735.690572377098;7743.10954240264;7746.070471543423;7622.047244094489;7664.425168112358;7665.118511371208;7676.232376762335;7681.116671582784;37928.620713792785;38305;38343.808280958416;38355.232895341935;38392.23215535674;3054.9847250763605;3078.846057697129;3145.653978062411;3169.556262123305;3351.463765797461;28376.148715538566;28437;28940.27649308763;29828.70171298281;30088.09735707932;3274.9836250818594;3285.78642388244;3315.4340371953685;3324.6176689680515;3336.0492259705866;244369;245718;246349;247304.76347618326;248496;28888.27779305492;52292.21561676578;52941.7352913233;53017;53064.204036939474;30735.69264307373;32252.19369515781;32416.351672966408;32579.674203257666;32590.674093258764;13599.456021759217;13726.725465490634;13869.861301387058;13872.028957973047;13873.791893121612;11547.191696581158;11720.179587428798;11733;11746.17776755619;11754.11844111695;7807;7821.5698136601895;7842.686292548348;7908.209179082046;7920.841583168304;3073.892413765552;3094.2883136878645;3099.1322429719717;3154.227214332503;3167.5407065975305;849.2738708404349;883.9204471597587;898.5417437106992;993.8173573447574;1009.3287963504334;28055.64394240732;28440.8577957113;28648.85675571609;28711;29178.562321565194;765.2462324610254;923.9260859131253;932.8134373125266;957.4973139102027;979.5983646704817;13916.234607096707;14697.26513674312;14758.262086895613;14983.250837458083;15071.397144114331;395351;398288;398639;398871;398948;48269.517304827204;48867.75566122147;49164.75417622889;49167.26249106266;49182.75408623005;1082.2262082610814;1127.5827943660897;1159.8492196014633;1168.2172944127456;1184.3959580613948;27927;28908.7109128906;29504.40991180207;29919.85040074786;30026.84986575053;42560.78719606506;43065.78467107644;43094.35358469688;43145.35281970836;43163;295726;297272;297773;298387;299282;24883.87558062198;25917.48165036651;25936.351591210372;26953.86523067372;27067.323316917234;41474.53535845186;41953;42605.4612900321;42912.78543607262;43041.78479107584;36016;36139.457908131924;36143.81928090343;36432.63567364292;37076;36272.45591316185;36751.632483674824;36856.44715329219;37261.25477490489;37881.62118378781;35762.628837636796;36274.818625907756;36430.63569364379;36991.8150409257;37050.444243335834;30973.845130775102;33047.50428743619;33334.49998250077;33485.33029339448;33661.663383365856;24782.38044048913;24869.25392238236;25245.873770631028;25293.747062529874;25690.871545642152 04:05:37 INFO - PROCESS | 12442 | |2;jquery.html;341.99487007694876;360.54211151837166;364.2278369855906;370.2003672068332;383.06149932664977;12452.626421207367;16336.346546138155;16782.496525104252;16799.83200167998;17900;16644.1677916104;17042.233099510522;17143.828561714377;17346.739798903025;17497;5003.974980125095;5053.5451809337155;5135.768890399922;5157.355330583677;5160.8709782255555;3757.4176002719623;5071.56891664209;5157.71632560209;5666.348369937468;6095.451409373156;6030.427109424598;6121.571489995702;6131.693415329238;6131.7240724167295;6132.693365331738;5932.347441781422;6010.9098363524145;6021.157038014681;6035.668038257894;6047.909281360783;5106.463821298785;5300.072487314743;5316;5318.335208098988;5325.281087053237;20922;21437.462818635064;21440.14239430421;21457.5708485831;21567.24514641996;1876.352658332873;1961.4900125967351;1996.1316827180078;2113.8837363944976;2154.762976072631;13220.6694832629;13305.600831975056;13489.662758431117;13492.797608035888;14071.015028947977;1700.2688843797162;1730.5154556724137;1740.4865564658455;1755.1295155942234;1777.1025632055857;67419;73195;74466.25533744648;74744;74886.62556687236;9574.542510768795;9679.225661947028;9898.30711850178;10045.849312260321;10067.1442927352;13435.12671676338;13448;13455.932720336335;13468.663283417993;13490;7547.773566793005;7814.10137834145;7818.8827167592535;7949.284564389232;8020.679172833022;7673.424493163036;7723.420743444264;7728.111267204344;7912.960435197787;7987.760367188994;5942.405759424023;6118.265808102967;6206.286277078104;6276.823768767342;6414.422701956846;1741.3644019932633;1744.5289771761554;1771.973420398695;1774.254812978552;1795.3357257814691;830.3855147191156;840.2731637133958;866.5970323799463;873.8732883731823;886.5611522296505;18094.45716628477;18106.728399074025;18818.435446936615;19033.90483047576;19160;834.3158609939912;834.6828205282076;840.4117118017413;873.4628203654706;937.6952490440634;9357.298202634829;10619.706282457413;10622.203334749925;10635.415052172204;10733.892661073445;387896;398061;399824;400231;400415.95840415725;81145.59427202944;81719.59140204261;81865.59067204625;86031;86383;881.405051590174;906.8141031088611;908.8863892013499;909.5088652127777;933.9813203735887;15884.682306353578;15989.360425583312;16554.503364899072;16577.254023568727;16651.41720039793;36217.27565448729;36586.268274633825;37419.97343267741;39468.80265598654;42156.15687686291;50548.24177637265;53492;53956;54193.45806542042;54457.72771136119;7335.7432489862595;7499.175090740124;7728.8454230916195;7743.5353878767455;7946;10011.949940250252;11679.649610511698;12014.519419223134;12092.214006089927;12350.876491234972;9784.60861565558;9879.456629885426;10247.897521024694;10263.486825658538;10589.311694739818;10436.530356134144;10440.843387349043;10570;10594.523246454082;11063.612773553208;9849;10005.299629025898;10168.847467288144;10308.659874216304;10500.369977801356;6772.93227067723;6962.895556566555;7060.082189315356;7245.818854528679;8091.029076510857;8196.877046844422;9572.617095316109;9747;10304.124149447382;10437 04:05:37 INFO - PROCESS | 12442 | |3;mootools.html;1266.6453393049937;1337.511808190011;1398.9090709103907;1434.906731062481;1659.518739565526;6745.5615385;7539.962300188498;7767.766966990986;7793.415493837956;8004;7839.529628222311;7895.368370530358;7899.4865333753405;7903.525788452697;7907.683692652301;2146.763855975845;2171.1206961180746;2172.0660116150048;2172.326578760589;2175.3147758456034;2105.6841473778904;2145.3027765976044;2162.016282591417;2163.1996161420298;2163.7836216378314;2113.7991890770354;2138.080625331107;2144.603248399051;2144.6246906791325;2146.677998300252;1919.89440580768;1923.0480911948605;1923.9230430782752;1929.527265819883;1929.7491326127654;1996.5707372914749;2031.8476114291486;2061.1446249806313;2063.515073957634;2066.617675729995;7840.059192896889;7850.293473587348;7888.25061619151;7915.12933577306;7925.247101525403;4489.820407183709;4626.560476754703;4633.745144017078;4634.490206077328;4639.512851150615;2765.2395591212576;2792.3158826087533;2877.8848846046135;2884.7210703544174;2895.348546577003;909.9909000910038;914.1909410172045;934.4720233068363;940.6566603189787;941.9340646154772;10519.684409467729;10739.62411315608;10741.731456713567;10746.462676866126;10754.408507532082;4090.529589097292;4176.2900306947895;4249.3413520904305;4269.466316710411;4270.466191726035;9216.262698984065;9300.348975571645;9302;9309.627614895327;9311.580978855967;4746.976265118652;4752.833650822205;4755.476897541268;4758.5241475852135;4761.35721677576;3712.1833196696684;4121.423000779893;4250.617444429954;4336.392904993303;4337.306031035082;3612.6748592626263;3632.073821175636;3766.886993390203;3776.811159442017;3806.410006449005;1135.3528488761326;1137.6871360375796;1144.862616486027;1145.7651181507772;1159.0322081062238;833.2750507058777;842.9199226073513;844.8099177685094;860.3805260212637;876.5047748022347;5131.461196574381;5147.0992576298995;5318.095923692904;5342.519173274424;5428.945710542844;378.1661436532439;400.4593798372174;404.7793952296006;405.06026019634163;408.93457046872356;3561.8753343633366;3686.9262614747554;3730.925381492355;3738.1962877981364;3862.9034274143373;9455.763605909908;9815.509224538744;9876.555555000043;9883;9906.306558540833;9791.755206119904;9810.950945245228;9837.508124593885;9844.310898237198;9848.753781155385;7001.054857594159;7120;7234.457415693844;7242.275772422821;7245.89131163033;5126.719768283822;5262.500062494057;5273.077211488051;5314.734263286743;5459.781608735765;8982;9035.186833185046;9040.909590904006;9062.184403403726;9075.183233509017;8609.526476043879;8952.597133128895;8973.37186396972;9145.451272923647;9286.442813430946;5898.144769008555;6344.206974128234;6466.935330646634;6602.042703807825;6608.570442921195;7037.7184912602925;7271.127464704269;7277;7304.269573042653;7314.96342518284;6525.608463492205;6529.67351632407;6584.703688333941;6705.932940670531;6735.730570777114;6167.722452489738;6256.937430625636;6304.621722696653;6406.878796210738;6441.420272175526;6196.876062478635;6312.495000400048;6312.652804095819;6313.40022697843;6322.525810564134;4172.582741725803;4203.390508376207;4220.303649897733;4302.268614335508;4397.36238245459;4574.176648203355;4574.405327307429;4610.377599024089;4622.953770462387;4718.245080787195 04:05:37 INFO - PROCESS | 12442 | |4;prototype.html;291.8879070740479;294.3817982237302;304.1908523327947;305.9204606802231;306.50040434092426;4653.720776753389;4744.027474367755;4768.213244814601;4829.855104346871;4839.903201935959;4636.837710680127;4752.334673145762;4767.713937163772;4773.260144677581;4785.66500344976;3780.4707340972286;3786.8295926683363;3798.3542797724385;3810.485584446106;3818.3127037133304;3604.1710406606367;3698.722595805312;3786.413105968579;3802.2205447883257;3817.3892177251646;3752.5684546277116;3764.3600587899987;3767.3972164453694;3777.8299976501125;3780.6786423154067;3736.9626303737023;3775.9622403775884;3777.301199278142;3784.8486060557543;3785.4889589905288;3354.9329013419597;3359.8320083995704;3364.2262279675565;3369.8989030328885;3372.207531230159;4523.208438523246;4601.976990115061;4633.4208223972;4645.558671926162;4702.882427939295;4265.594768496988;4273.957260427418;4274.5939135782055;4284.914301713949;4308.578169204186;4975.328330675348;4975.477574854624;4982.352294201767;4990.65065445419;5029.245613158019;3577.409727394977;3580.785152890835;3599.280143971216;3653.5250417445563;3678.6505281998434;5165.793368265265;5189.143791274435;5205.583553315726;5209.843704688866;5232.215167724834;3705.5368078990127;3707.536557930259;3716.4948195980132;3722.9627703722617;3738.1962877981364;3787.6023017583307;3794.867179648699;3795.070207799052;3796.5823759386444;3808.790516521618;4151.252774500558;4192.098698779777;4209.284421648327;4235.2800023996;4270.167317373144;4951.306817045616;4955.132851751001;4972.279019542146;4973.035650917867;4986.32684587576;1216.2763155922212;1262.797952327623;1266.5503746170161;1291.9612411627666;1298.3702904091508;880.8238352329431;895.9686410975585;913.2556966072763;923.1414784250711;925.5187302602609;4347.913041739147;4359.716618419792;4361.956380436219;4364.8254069837485;4366.118361881143;5100.69395836251;5104.753908280345;5108.1865937592365;5113.8721531961255;5124.769385377667;4343.261645520269;4369.191699535532;4370.7159034662645;4382.430284063115;4385.912281754411;2280.247518318951;2390.1237528540014;2427.6358546218207;2440.7193172785;2448.6694296269775;4264.722793018444;4279.614834664895;4327.2643650579475;4358.498772641186;4359.869203923888;4621.976890115528;4644.651651126179;4652.976735116303;4727.787249573708;4742.762861856893;4285.400043993844;4493.662975276802;4832.251001094836;4864.759486330963;4868.342773725572;5193.662411943212;5201.479852014768;5214.817481388133;5215.217717342429;5217.373915130209;5088.491150884882;5111.718855462986;5129.820456284012;5146.948530514647;5152.562032227303;4679.859604211879;4821.300911367762;4830.154722923545;4840.177169881058;4847.151748443937;3646.161382881899;3671.963280367269;3672.8163591820835;3678.172411207457;3690.870819521304;4954.677945933503;5212.452692467236;5225.790968361223;5228.542584985051;5236.790528378822;4875.658703890694;5036.949630503648;5038.622103342191;5045.268436076675;5060.949390506048;5026.44709082008;5032.27032080339;5042.823501177441;5061.544460998528;5063.26582645507;4795.20879054952;4956.454789973172;4960.677555958851;4966.230234313687;4979;5054.393472783289;5071.467495913023;5098.5526947066355;5103.566196873159;5105.566026887607;4990.950090499048;4992.6255530834605;4996.800127994839;5000.274960130688;5015.623828212826 04:05:37 INFO - PROCESS | 12442 | |5;yui.html;604.9190783704058;680.5780416141987;688.8140202145421;698.2458944340111;702.1889717376432;10710.625128120517;12493.750124997507;13407.86033187179;13469.326533673315;14627.853721462781;13500.864991350083;14012;14139.293035348242;14147.151170929752;14151.504697335595;771.7260372567728;794.126460893016;796.1163108949062;796.3271035974622;797.0594698256056;633.1041576169714;747.55146911853;768.9269519395676;769.6498093367533;777.9105402878685;827.9461834980738;831.966721331146;833.2833762963846;835.2858306148247;836.9246767790897;824.3899514359331;826.3265438667466;826.4256341842422;826.5949684654476;826.5990994367727;600.4655856287907;600.4865839707031;600.6486205569747;603.873186630808;606.3572613030202;13447.462101516025;13503.122297050759;13509.121907076107;13534;13648.658783530393;13042.608721738363;13052.477900883954;13176.802347964789;13320.267385293804;13416;9983.800323993552;9984.400935943866;10029.849552256723;10210.795784084277;10407;3187.0916788715335;3206.422843888099;3209.5667084943466;3238.676132386766;3256.1045712429036;59431.10853337203;59886.700566497326;60248.698756506376;61824.99999999955;61933.69033154805;10094.343867648578;10114.393136411838;10181.796364072825;10260.486975651187;10652.307600005975;19158.904205478884;19240.71138932917;19243;19244.326448574233;19343.4196974091;6637.435817955431;6688;6717.798466045929;6851.2806155353965;6906.827329316808;3588.5155504006625;3657.3599620066384;3692.2800053989213;3693.963060369416;3752.2870654575545;3323.11937336606;3495.0563347896436;3504.8247587620517;3508.8070156141657;3519.225770330523;571.3743450921235;571.5484767034079;574.7413663851283;577.7053702611614;588.3351812451901;552.6628756458557;623.2956758862456;623.6227082615044;624.1418050181001;625.524601303013;7282.162551306562;7314.341709246087;7395.297446742551;7594.696212151563;7630.351420129351;538.0798833993914;551.0521902327989;567.7728908436659;577.4955348283373;591;3539.239063601338;3613.8915832525067;3704.7221458390727;3744.9063773405883;3800.1449673823167;108741;109951.45024274828;110098;110679;113936;94075;94965.5251723737;95619;96218;96482.51758741161;78633.60683196547;80676.99999999882;81000.59499702464;81024;81910;7054.365107140382;7481.476296659399;7555.433342499225;7573.35626471756;7922.326602238643;31359.529607056367;32130.83934580312;40933.1813363737;43436.348454772575;44495.55504444914;59585.40414595798;61710;62152.68923655353;63709.36290637034;64323.678381609665;10838.837417438586;11138.94430527842;11169.497372618414;11341.659750207507;11450.656480305604;12198.695032624255;12340.50637974471;12834.422450989541;13436.93281533586;13556.11885227457;10527.315724477885;10756.515956781806;10767.461626918466;10995.945020274847;11403.543858245577;10054.64808731691;10128.746781330527;10230.130438912776;10397.896021039693;10617.41604211745;10409.791804163722;10579.682609521727;10663.8933610666;10678.946605266923;10729.463526823783;9401.529923503935;9950.502474876082;10115.898841011496;10286.485675716334;10482.68551943443;9017.504037278013;9195.35632505745;9339.906600933904;9505.809883802423;9524.095121000357 04:05:37 INFO - PROCESS | 12442 | __end_tp_report 04:05:37 INFO - PROCESS | 12442 | __start_cc_report 04:05:37 INFO - PROCESS | 12442 | _x_x_mozilla_cycle_collect,1140 04:05:37 INFO - PROCESS | 12442 | __end_cc_report 04:05:37 INFO - PROCESS | 12442 | __startTimestamp1469185537485__endTimestamp 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | ------- Summary: start ------- 04:05:37 INFO - PROCESS | 12442 | Number of tests: 6 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | [#0] dojo.html Cycles:165 Average:4629.84 Median:4727.83 stddev:482.66 (10.2%) stddev-sans-first:456.41 04:05:37 INFO - PROCESS | 12442 | Values: 2574.2 2575.4 2609.9 2617.6 2621.0 4534.9 4633.5 4671.1 4727.8 4731.6 4660.8 4679.9 4689.6 4699.3 4707.5 4283.8 4355.6 4356.7 4365.1 4366.7 4327.5 4362.5 4376.6 4411.9 4449.0 4428.0 4473.0 4478.1 4489.0 4489.2 4179.0 4202.7 4395.4 4429.8 4441.3 4224.0 4225.3 4271.3 4278.1 4279.8 4615.4 4634.1 4640.4 4649.9 4709.0 4933.6 4983.1 5015.1 5024.2 5029.7 4848.4 4910.9 4979.4 4987.9 5057.2 4686.0 4724.8 4725.6 4728.8 4729.6 5029.1 5166.8 5172.8 5174.1 5179.9 4900.1 4918.8 4936.2 5005.2 5015.9 5049.9 5057.5 5057.6 5098.9 5112.3 4821.4 4992.2 5003.9 5011.7 5057.7 4852.5 4912.8 4919.6 4925.4 4929.6 4225.8 4255.8 4265.1 4267.0 4269.0 3712.0 3773.2 3775.9 3778.1 3778.4 4259.9 4269.7 4306.9 4313.6 4328.3 5020.6 5029.3 5049.6 5054.7 5076.9 4108.9 4153.7 4281.6 4285.7 4324.3 4481.3 4496.8 4519.5 4535.7 4542.2 4624.4 4645.7 4647.3 4650.8 4692.0 4863.9 5011.2 5026.9 5029.9 5062.3 4536.7 4627.3 4662.0 4664.6 4721.1 4967.3 4970.5 4983.4 4983.7 5012.5 5020.3 5034.1 5042.7 5047.9 5095.1 4689.0 4806.6 4841.8 4848.1 4922.7 4871.0 4877.2 4878.9 4883.2 4908.6 4767.7 4784.8 4828.1 4832.1 4842.1 4743.0 4869.4 4889.9 4911.0 4998.5 4801.1 4831.3 4831.4 4841.2 4874.1 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | [#1] ext.html Cycles:180 Average:45998.53 Median:36592.13 stddev:84512.16 (231.0%) stddev-sans-first:84680.39 04:05:37 INFO - PROCESS | 12442 | Values: 566.3 583.2 590.2 593.3 601.1 27058.3 40189.2 41775.2 41814.8 45055.0 40695.2 41166.6 41181.4 41216.2 41236.4 7521.5 7626.0 7641.4 7645.0 7675.6 7640.6 7734.1 7734.5 7739.5 7739.8 7708.8 7754.1 7758.7 7763.7 7777.5 7689.3 7694.5 7735.7 7743.1 7746.1 7622.0 7664.4 7665.1 7676.2 7681.1 37928.6 38305.0 38343.8 38355.2 38392.2 3055.0 3078.8 3145.7 3169.6 3351.5 28376.1 28437.0 28940.3 29828.7 30088.1 3275.0 3285.8 3315.4 3324.6 3336.0 244369.0 245718.0 246349.0 247304.8 248496.0 28888.3 52292.2 52941.7 53017.0 53064.2 30735.7 32252.2 32416.4 32579.7 32590.7 13599.5 13726.7 13869.9 13872.0 13873.8 11547.2 11720.2 11733.0 11746.2 11754.1 7807.0 7821.6 7842.7 7908.2 7920.8 3073.9 3094.3 3099.1 3154.2 3167.5 849.3 883.9 898.5 993.8 1009.3 28055.6 28440.9 28648.9 28711.0 29178.6 765.2 923.9 932.8 957.5 979.6 13916.2 14697.3 14758.3 14983.3 15071.4 395351.0 398288.0 398639.0 398871.0 398948.0 48269.5 48867.8 49164.8 49167.3 49182.8 1082.2 1127.6 1159.8 1168.2 1184.4 27927.0 28908.7 29504.4 29919.9 30026.8 42560.8 43065.8 43094.4 43145.4 43163.0 295726.0 297272.0 297773.0 298387.0 299282.0 24883.9 25917.5 25936.4 26953.9 27067.3 41474.5 41953.0 42605.5 42912.8 43041.8 36016.0 36139.5 36143.8 36432.6 37076.0 36272.5 36751.6 36856.4 37261.3 37881.6 35762.6 36274.8 36430.6 36991.8 37050.4 30973.8 33047.5 33334.5 33485.3 33661.7 24782.4 24869.3 25245.9 25293.7 25690.9 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | [#2] jquery.html Cycles:180 Average:25199.98 Median:23580.07 stddev:65756.83 (278.9%) stddev-sans-first:65914.81 04:05:37 INFO - PROCESS | 12442 | Values: 342.0 360.5 364.2 370.2 383.1 12452.6 16336.3 16782.5 16799.8 17900.0 16644.2 17042.2 17143.8 17346.7 17497.0 5004.0 5053.5 5135.8 5157.4 5160.9 3757.4 5071.6 5157.7 5666.3 6095.5 6030.4 6121.6 6131.7 6131.7 6132.7 5932.3 6010.9 6021.2 6035.7 6047.9 5106.5 5300.1 5316.0 5318.3 5325.3 20922.0 21437.5 21440.1 21457.6 21567.2 1876.4 1961.5 1996.1 2113.9 2154.8 13220.7 13305.6 13489.7 13492.8 14071.0 1700.3 1730.5 1740.5 1755.1 1777.1 67419.0 73195.0 74466.3 74744.0 74886.6 9574.5 9679.2 9898.3 10045.8 10067.1 13435.1 13448.0 13455.9 13468.7 13490.0 7547.8 7814.1 7818.9 7949.3 8020.7 7673.4 7723.4 7728.1 7913.0 7987.8 5942.4 6118.3 6206.3 6276.8 6414.4 1741.4 1744.5 1772.0 1774.3 1795.3 830.4 840.3 866.6 873.9 886.6 18094.5 18106.7 18818.4 19033.9 19160.0 834.3 834.7 840.4 873.5 937.7 9357.3 10619.7 10622.2 10635.4 10733.9 387896.0 398061.0 399824.0 400231.0 400416.0 81145.6 81719.6 81865.6 86031.0 86383.0 881.4 906.8 908.9 909.5 934.0 15884.7 15989.4 16554.5 16577.3 16651.4 36217.3 36586.3 37420.0 39468.8 42156.2 50548.2 53492.0 53956.0 54193.5 54457.7 7335.7 7499.2 7728.8 7743.5 7946.0 10011.9 11679.6 12014.5 12092.2 12350.9 9784.6 9879.5 10247.9 10263.5 10589.3 10436.5 10440.8 10570.0 10594.5 11063.6 9849.0 10005.3 10168.8 10308.7 10500.4 6772.9 6962.9 7060.1 7245.8 8091.0 8196.9 9572.6 9747.0 10304.1 10437.0 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | [#3] mootools.html Cycles:180 Average:5100.05 Median:5129.09 stddev:2923.51 (57.0%) stddev-sans-first:2917.52 04:05:37 INFO - PROCESS | 12442 | Values: 1266.6 1337.5 1398.9 1434.9 1659.5 6745.6 7540.0 7767.8 7793.4 8004.0 7839.5 7895.4 7899.5 7903.5 7907.7 2146.8 2171.1 2172.1 2172.3 2175.3 2105.7 2145.3 2162.0 2163.2 2163.8 2113.8 2138.1 2144.6 2144.6 2146.7 1919.9 1923.0 1923.9 1929.5 1929.7 1996.6 2031.8 2061.1 2063.5 2066.6 7840.1 7850.3 7888.3 7915.1 7925.2 4489.8 4626.6 4633.7 4634.5 4639.5 2765.2 2792.3 2877.9 2884.7 2895.3 910.0 914.2 934.5 940.7 941.9 10519.7 10739.6 10741.7 10746.5 10754.4 4090.5 4176.3 4249.3 4269.5 4270.5 9216.3 9300.3 9302.0 9309.6 9311.6 4747.0 4752.8 4755.5 4758.5 4761.4 3712.2 4121.4 4250.6 4336.4 4337.3 3612.7 3632.1 3766.9 3776.8 3806.4 1135.4 1137.7 1144.9 1145.8 1159.0 833.3 842.9 844.8 860.4 876.5 5131.5 5147.1 5318.1 5342.5 5428.9 378.2 400.5 404.8 405.1 408.9 3561.9 3686.9 3730.9 3738.2 3862.9 9455.8 9815.5 9876.6 9883.0 9906.3 9791.8 9811.0 9837.5 9844.3 9848.8 7001.1 7120.0 7234.5 7242.3 7245.9 5126.7 5262.5 5273.1 5314.7 5459.8 8982.0 9035.2 9040.9 9062.2 9075.2 8609.5 8952.6 8973.4 9145.5 9286.4 5898.1 6344.2 6466.9 6602.0 6608.6 7037.7 7271.1 7277.0 7304.3 7315.0 6525.6 6529.7 6584.7 6705.9 6735.7 6167.7 6256.9 6304.6 6406.9 6441.4 6196.9 6312.5 6312.7 6313.4 6322.5 4172.6 4203.4 4220.3 4302.3 4397.4 4574.2 4574.4 4610.4 4623.0 4718.2 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | [#4] prototype.html Cycles:180 Average:4133.24 Median:4635.13 stddev:1195.65 (25.8%) stddev-sans-first:1163.72 04:05:37 INFO - PROCESS | 12442 | Values: 291.9 294.4 304.2 305.9 306.5 4653.7 4744.0 4768.2 4829.9 4839.9 4636.8 4752.3 4767.7 4773.3 4785.7 3780.5 3786.8 3798.4 3810.5 3818.3 3604.2 3698.7 3786.4 3802.2 3817.4 3752.6 3764.4 3767.4 3777.8 3780.7 3737.0 3776.0 3777.3 3784.8 3785.5 3354.9 3359.8 3364.2 3369.9 3372.2 4523.2 4602.0 4633.4 4645.6 4702.9 4265.6 4274.0 4274.6 4284.9 4308.6 4975.3 4975.5 4982.4 4990.7 5029.2 3577.4 3580.8 3599.3 3653.5 3678.7 5165.8 5189.1 5205.6 5209.8 5232.2 3705.5 3707.5 3716.5 3723.0 3738.2 3787.6 3794.9 3795.1 3796.6 3808.8 4151.3 4192.1 4209.3 4235.3 4270.2 4951.3 4955.1 4972.3 4973.0 4986.3 1216.3 1262.8 1266.6 1292.0 1298.4 880.8 896.0 913.3 923.1 925.5 4347.9 4359.7 4362.0 4364.8 4366.1 5100.7 5104.8 5108.2 5113.9 5124.8 4343.3 4369.2 4370.7 4382.4 4385.9 2280.2 2390.1 2427.6 2440.7 2448.7 4264.7 4279.6 4327.3 4358.5 4359.9 4622.0 4644.7 4653.0 4727.8 4742.8 4285.4 4493.7 4832.3 4864.8 4868.3 5193.7 5201.5 5214.8 5215.2 5217.4 5088.5 5111.7 5129.8 5146.9 5152.6 4679.9 4821.3 4830.2 4840.2 4847.2 3646.2 3672.0 3672.8 3678.2 3690.9 4954.7 5212.5 5225.8 5228.5 5236.8 4875.7 5036.9 5038.6 5045.3 5060.9 5026.4 5032.3 5042.8 5061.5 5063.3 4795.2 4956.5 4960.7 4966.2 4979.0 5054.4 5071.5 5098.6 5103.6 5105.6 4991.0 4992.6 4996.8 5000.3 5015.6 04:05:37 INFO - PROCESS | 12442 | 04:05:37 INFO - PROCESS | 12442 | [#5] yui.html Cycles:180 Average:18297.97 Median:22516.82 stddev:27577.24 (122.5%) stddev-sans-first:27622.60 04:05:37 INFO - PROCESS | 12442 | Values: 604.9 680.6 688.8 698.2 702.2 10710.6 12493.8 13407.9 13469.3 14627.9 13500.9 14012.0 14139.3 14147.2 14151.5 771.7 794.1 796.1 796.3 797.1 633.1 747.6 768.9 769.6 777.9 827.9 832.0 833.3 835.3 836.9 824.4 826.3 826.4 826.6 826.6 600.5 600.5 600.6 603.9 606.4 13447.5 13503.1 13509.1 13534.0 13648.7 13042.6 13052.5 13176.8 13320.3 13416.0 9983.8 9984.4 10029.8 10210.8 10407.0 3187.1 3206.4 3209.6 3238.7 3256.1 59431.1 59886.7 60248.7 61825.0 61933.7 10094.3 10114.4 10181.8 10260.5 10652.3 19158.9 19240.7 19243.0 19244.3 19343.4 6637.4 6688.0 6717.8 6851.3 6906.8 3588.5 3657.4 3692.3 3694.0 3752.3 3323.1 3495.1 3504.8 3508.8 3519.2 571.4 571.5 574.7 577.7 588.3 552.7 623.3 623.6 624.1 625.5 7282.2 7314.3 7395.3 7594.7 7630.4 538.1 551.1 567.8 577.5 591.0 3539.2 3613.9 3704.7 3744.9 3800.1 108741.0 109951.5 110098.0 110679.0 113936.0 94075.0 94965.5 95619.0 96218.0 96482.5 78633.6 80677.0 81000.6 81024.0 81910.0 7054.4 7481.5 7555.4 7573.4 7922.3 31359.5 32130.8 40933.2 43436.3 44495.6 59585.4 61710.0 62152.7 63709.4 64323.7 10838.8 11138.9 11169.5 11341.7 11450.7 12198.7 12340.5 12834.4 13436.9 13556.1 10527.3 10756.5 10767.5 10995.9 11403.5 10054.6 10128.7 10230.1 10397.9 10617.4 10409.8 10579.7 10663.9 10678.9 10729.5 9401.5 9950.5 10115.9 10286.5 10482.7 9017.5 9195.4 9339.9 9505.8 9524.1 04:05:37 INFO - PROCESS | 12442 | -------- Summary: end -------- 04:05:37 INFO - PROCESS | 12442 | 04:05:38 INFO - TEST-INFO | 12442: exit 0 04:05:38 INFO - TEST-OK | dromaeo_css | took 1120044ms 04:05:38 INFO - TEST-START | kraken 04:05:38 INFO - operating with platform_type : linux_ 04:05:38 INFO - Initialising browser for kraken test... 04:05:38 INFO - TEST-INFO | started process 12632 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYHhzKQ/profile http://localhost:35816/getInfo.html) 04:05:41 INFO - PROCESS | 12632 | JavaScript error: , line 0: uncaught exception: undefined 04:05:42 INFO - PROCESS | 12632 | __metrics Screen width/height:1600/1200 04:05:42 INFO - PROCESS | 12632 | colorDepth:24 04:05:42 INFO - PROCESS | 12632 | Browser inner width/height: 1024/697 04:05:42 INFO - PROCESS | 12632 | __metrics 04:05:43 INFO - PROCESS | 12632 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:05:43 INFO - PROCESS | 12632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:05:44 INFO - TEST-INFO | 12632: exit 0 04:05:44 INFO - Browser initialized. 04:05:44 INFO - Running cycle 1/1 for kraken test... 04:05:44 INFO - TEST-INFO | started process 12710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYHhzKQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:56 INFO - PROCESS | 12710 | RSS: Main: 159215616 04:05:56 INFO - PROCESS | 12710 | 04:05:59 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:00 INFO - PROCESS | 12710 | RSS: Main: 215560192 04:06:00 INFO - PROCESS | 12710 | 04:06:03 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-dft) 04:06:03 INFO - PROCESS | 12710 | RSS: Main: 192581632 04:06:03 INFO - PROCESS | 12710 | 04:06:06 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-fft) 04:06:07 INFO - PROCESS | 12710 | RSS: Main: 273133568 04:06:07 INFO - PROCESS | 12710 | 04:06:09 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35816/tests/kraken/driver.html?testName=audio-oscillator) 04:06:10 INFO - PROCESS | 12710 | RSS: Main: 181788672 04:06:10 INFO - PROCESS | 12710 | 04:06:12 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35816/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:13 INFO - PROCESS | 12710 | RSS: Main: 186089472 04:06:13 INFO - PROCESS | 12710 | 04:06:16 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35816/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:16 INFO - PROCESS | 12710 | RSS: Main: 223064064 04:06:16 INFO - PROCESS | 12710 | 04:06:20 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35816/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:20 INFO - PROCESS | 12710 | RSS: Main: 216936448 04:06:20 INFO - PROCESS | 12710 | 04:06:23 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35816/tests/kraken/driver.html?testName=json-parse-financial) 04:06:24 INFO - PROCESS | 12710 | RSS: Main: 219840512 04:06:24 INFO - PROCESS | 12710 | 04:06:26 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35816/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:26 INFO - PROCESS | 12710 | RSS: Main: 185831424 04:06:26 INFO - PROCESS | 12710 | 04:06:28 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:29 INFO - PROCESS | 12710 | RSS: Main: 190119936 04:06:29 INFO - PROCESS | 12710 | 04:06:31 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:32 INFO - PROCESS | 12710 | RSS: Main: 195010560 04:06:32 INFO - PROCESS | 12710 | 04:06:34 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:35 INFO - PROCESS | 12710 | RSS: Main: 191184896 04:06:35 INFO - PROCESS | 12710 | 04:06:38 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:38 INFO - PROCESS | 12710 | RSS: Main: 194154496 04:06:38 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | Cycle 1(1): loaded http://localhost:35816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35816/tests/kraken/driver.html?testName=ai-astar) 04:06:40 INFO - PROCESS | 12710 | RSS: Main: 244219904 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | __start_tp_report 04:06:40 INFO - PROCESS | 12710 | _x_x_mozilla_page_load 04:06:40 INFO - PROCESS | 12710 | _x_x_mozilla_page_load_details 04:06:40 INFO - PROCESS | 12710 | |i|pagename|runs| 04:06:40 INFO - PROCESS | 12710 | |0;ai-astar;106;116;114;115;117;115;114;113;114;117 04:06:40 INFO - PROCESS | 12710 | |1;audio-beat-detection;148;122;125;184;123;127;153;123;170;123 04:06:40 INFO - PROCESS | 12710 | |2;audio-dft;161;162;161;161;162;161;161;161;162;162 04:06:40 INFO - PROCESS | 12710 | |3;audio-fft;77;76;76;125;76;77;75;76;102;76 04:06:40 INFO - PROCESS | 12710 | |4;audio-oscillator;86;86;119;85;86;86;120;85;85;134 04:06:40 INFO - PROCESS | 12710 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;117;116 04:06:40 INFO - PROCESS | 12710 | |6;imaging-darkroom;147;146;147;146;147;146;147;147;145;146 04:06:40 INFO - PROCESS | 12710 | |7;imaging-desaturate;95;95;96;95;95;95;95;97;95;95 04:06:40 INFO - PROCESS | 12710 | |8;json-parse-financial;61;60;60;61;61;60;61;61;61;60 04:06:40 INFO - PROCESS | 12710 | |9;json-stringify-tinderbox;66;66;65;65;66;65;65;70;65;65 04:06:40 INFO - PROCESS | 12710 | |10;stanford-crypto-aes;85;80;79;78;79;79;78;79;79;82 04:06:40 INFO - PROCESS | 12710 | |11;stanford-crypto-ccm;130;129;128;129;138;134;127;126;184;139 04:06:40 INFO - PROCESS | 12710 | |12;stanford-crypto-pbkdf2;171;164;165;161;165;162;163;163;163;162 04:06:40 INFO - PROCESS | 12710 | |13;stanford-crypto-sha256-iterative;68;67;66;66;67;66;66;70;65;66 04:06:40 INFO - PROCESS | 12710 | __end_tp_report 04:06:40 INFO - PROCESS | 12710 | __start_cc_report 04:06:40 INFO - PROCESS | 12710 | _x_x_mozilla_cycle_collect,1981 04:06:40 INFO - PROCESS | 12710 | __end_cc_report 04:06:40 INFO - PROCESS | 12710 | __startTimestamp1469185600936__endTimestamp 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | ------- Summary: start ------- 04:06:40 INFO - PROCESS | 12710 | Number of tests: 14 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.14 (2.7%) stddev-sans-first:1.41 04:06:40 INFO - PROCESS | 12710 | Values: 106.0 116.0 114.0 115.0 117.0 115.0 114.0 113.0 114.0 117.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:137.50 stddev:22.74 (16.5%) stddev-sans-first:23.92 04:06:40 INFO - PROCESS | 12710 | Values: 148.0 122.0 125.0 184.0 123.0 127.0 153.0 123.0 170.0 123.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 04:06:40 INFO - PROCESS | 12710 | Values: 161.0 162.0 161.0 161.0 162.0 161.0 161.0 161.0 162.0 162.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:16.67 (21.9%) stddev-sans-first:17.51 04:06:40 INFO - PROCESS | 12710 | Values: 77.0 76.0 76.0 125.0 76.0 77.0 75.0 76.0 102.0 76.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:85.50 stddev:19.14 (22.4%) stddev-sans-first:19.87 04:06:40 INFO - PROCESS | 12710 | Values: 86.0 86.0 119.0 85.0 86.0 86.0 120.0 85.0 85.0 134.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:06:40 INFO - PROCESS | 12710 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 04:06:40 INFO - PROCESS | 12710 | Values: 147.0 146.0 147.0 146.0 147.0 146.0 147.0 147.0 145.0 146.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 04:06:40 INFO - PROCESS | 12710 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 04:06:40 INFO - PROCESS | 12710 | Values: 61.0 60.0 60.0 61.0 61.0 60.0 61.0 61.0 61.0 60.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:65.50 stddev:1.55 (2.4%) stddev-sans-first:1.64 04:06:40 INFO - PROCESS | 12710 | Values: 66.0 66.0 65.0 65.0 66.0 65.0 65.0 70.0 65.0 65.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:2.15 (2.7%) stddev-sans-first:1.20 04:06:40 INFO - PROCESS | 12710 | Values: 85.0 80.0 79.0 78.0 79.0 79.0 78.0 79.0 79.0 82.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:132.00 stddev:17.32 (13.1%) stddev-sans-first:18.21 04:06:40 INFO - PROCESS | 12710 | Values: 130.0 129.0 128.0 129.0 138.0 134.0 127.0 126.0 184.0 139.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.90 Median:163.50 stddev:2.81 (1.7%) stddev-sans-first:1.36 04:06:40 INFO - PROCESS | 12710 | Values: 171.0 164.0 165.0 161.0 165.0 162.0 163.0 163.0 163.0 162.0 04:06:40 INFO - PROCESS | 12710 | 04:06:40 INFO - PROCESS | 12710 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:1.42 (2.1%) stddev-sans-first:1.42 04:06:40 INFO - PROCESS | 12710 | Values: 68.0 67.0 66.0 66.0 67.0 66.0 66.0 70.0 65.0 66.0 04:06:40 INFO - PROCESS | 12710 | -------- Summary: end -------- 04:06:40 INFO - PROCESS | 12710 | 04:06:42 INFO - TEST-INFO | 12710: exit 0 04:06:42 INFO - TEST-OK | kraken | took 63790ms 04:06:42 INFO - SUITE-END | took 1183s 04:06:42 INFO - Completed test suite (00:19:44) 04:06:42 INFO - javascript benchmark 04:06:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2574.214864466337, 2575.420530380665, 2609.93475163121, 2617.5550156473396, 2620.9606855897164, 4534.909301813962, 4633.467151277604, 4671.112488627158, 4727.8345257915935, 4731.621470282378, 4660.766961651921, 4679.906401871961, 4689.624830013599, 4699.2951057341415, 4707.529247075291, 4283.828646854122, 4355.629771469429, 4356.6514678825615, 4365.083332500179, 4366.672499562545, 4327.545607711192, 4362.454693163355, 4376.584224498668, 4411.933820992687, 4448.97775511122, 4428.0, 4473.038296766194, 4478.104379124172, 4489.0, 4489.169503641837, 4178.997040710238, 4202.684798640114, 4395.406620106277, 4429.756363400012, 4441.2671909135115, 4224.007358270804, 4225.302825033866, 4271.273883439822, 4278.122984788142, 4279.807408666617, 4615.399998000272, 4634.073185362907, 4640.396748422717, 4649.930251046203, 4708.95291047092, 4933.555979961785, 4983.053219888209, 5015.122353588144, 5024.196128619404, 5029.723365214912, 4848.369711937426, 4910.901781964377, 4979.352684151074, 4987.850364489072, 5057.190849464105, 4686.039361930796, 4724.834630787906, 4725.64557658177, 4728.810847566127, 4729.55069268419, 5029.069622119846, 5166.767495462713, 5172.844814655567, 5174.094533456705, 5179.870503237374, 4900.093482705711, 4918.827841025619, 4936.2102063669645, 5005.199168133081, 5015.899682006412, 5049.924251136236, 5057.494250574987, 5057.620678449131, 5098.923516147261, 5112.309838171874, 4821.445533763592, 4992.226204938241, 5003.899922001539, 5011.699298042129, 5057.671251368649, 4852.51474852519, 4912.803487860517, 4919.581835544019, 4925.359703238556, 4929.556339929423, 4225.809838557272, 4255.808488617958, 4265.104328091135, 4267.018585725301, 4269.0, 3711.9814400928362, 3773.1699026214187, 3775.867844625425, 3778.074371778932, 3778.3954567269107, 4259.87220383389, 4269.7224680395675, 4306.913861722748, 4313.61177494027, 4328.329108988112, 5020.623453241022, 5029.270755740397, 5049.5707864831165, 5054.671446355975, 5076.898462030738, 4108.897277568085, 4153.7300075494995, 4281.550437204049, 4285.721428107226, 4324.308110702273, 4481.260592002348, 4496.752678602643, 4519.502854685982, 4535.659825513034, 4542.227821270392, 4624.421947256593, 4645.744484053274, 4647.34937108805, 4650.790714417927, 4692.0381321828945, 4863.8540843774745, 5011.248312753116, 5026.899462010812, 5029.9245511318095, 5062.265971434195, 4536.6824322297125, 4627.282771170474, 4662.020975595163, 4664.556867097756, 4721.055788842176, 4967.254911763264, 4970.478099799469, 4983.426905905722, 4983.6760610560195, 5012.548870601663, 5020.347354843847, 5034.144195486703, 5042.722650254272, 5047.924281135859, 5095.082885080721, 4689.0, 4806.615470762261, 4841.782119804688, 4848.10310092634, 4922.655414120977, 4870.95129048705, 4877.244027175793, 4878.878028049327, 4883.243097319921, 4908.558229759196, 4767.69010014348, 4784.832530861403, 4828.058528586892, 4832.130216561053, 4842.080004799077, 4742.976285118552, 4869.366982292279, 4889.9022019560125, 4910.950890491049, 4998.500149984972, 4801.063792560417, 4831.323614693875, 4831.396075490564, 4841.2496063110275, 4874.147024270809], "value": 4599.055396096834, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.2893069198157, 583.1981026089128, 590.2002786224665, 593.285091464785, 601.1283638723852, 27058.32354191144, 40189.196216075696, 41775.164496710124, 41814.79092604537, 45055.0, 40695.18609627813, 41166.58833411665, 41181.38227926583, 41216.175676486455, 41236.38145427813, 7521.511101778394, 7626.0, 7641.350485208736, 7645.044369453818, 7675.577843218621, 7640.5797681127515, 7734.1492435832015, 7734.458587898849, 7739.5356278623185, 7739.7678069658, 7708.845823083563, 7754.108277548098, 7758.6896524138965, 7763.728269510569, 7777.455578109534, 7689.269519395649, 7694.461387702863, 7735.690572377098, 7743.10954240264, 7746.070471543423, 7622.047244094489, 7664.425168112358, 7665.118511371208, 7676.232376762335, 7681.116671582784, 37928.620713792785, 38305.0, 38343.808280958416, 38355.232895341935, 38392.23215535674, 3054.9847250763605, 3078.846057697129, 3145.653978062411, 3169.556262123305, 3351.463765797461, 28376.148715538566, 28437.0, 28940.27649308763, 29828.70171298281, 30088.09735707932, 3274.9836250818594, 3285.78642388244, 3315.4340371953685, 3324.6176689680515, 3336.0492259705866, 244369.0, 245718.0, 246349.0, 247304.76347618326, 248496.0, 28888.27779305492, 52292.21561676578, 52941.7352913233, 53017.0, 53064.204036939474, 30735.69264307373, 32252.19369515781, 32416.351672966408, 32579.674203257666, 32590.674093258764, 13599.456021759217, 13726.725465490634, 13869.861301387058, 13872.028957973047, 13873.791893121612, 11547.191696581158, 11720.179587428798, 11733.0, 11746.17776755619, 11754.11844111695, 7807.0, 7821.5698136601895, 7842.686292548348, 7908.209179082046, 7920.841583168304, 3073.892413765552, 3094.2883136878645, 3099.1322429719717, 3154.227214332503, 3167.5407065975305, 849.2738708404349, 883.9204471597587, 898.5417437106992, 993.8173573447574, 1009.3287963504334, 28055.64394240732, 28440.8577957113, 28648.85675571609, 28711.0, 29178.562321565194, 765.2462324610254, 923.9260859131253, 932.8134373125266, 957.4973139102027, 979.5983646704817, 13916.234607096707, 14697.26513674312, 14758.262086895613, 14983.250837458083, 15071.397144114331, 395351.0, 398288.0, 398639.0, 398871.0, 398948.0, 48269.517304827204, 48867.75566122147, 49164.75417622889, 49167.26249106266, 49182.75408623005, 1082.2262082610814, 1127.5827943660897, 1159.8492196014633, 1168.2172944127456, 1184.3959580613948, 27927.0, 28908.7109128906, 29504.40991180207, 29919.85040074786, 30026.84986575053, 42560.78719606506, 43065.78467107644, 43094.35358469688, 43145.35281970836, 43163.0, 295726.0, 297272.0, 297773.0, 298387.0, 299282.0, 24883.87558062198, 25917.48165036651, 25936.351591210372, 26953.86523067372, 27067.323316917234, 41474.53535845186, 41953.0, 42605.4612900321, 42912.78543607262, 43041.78479107584, 36016.0, 36139.457908131924, 36143.81928090343, 36432.63567364292, 37076.0, 36272.45591316185, 36751.632483674824, 36856.44715329219, 37261.25477490489, 37881.62118378781, 35762.628837636796, 36274.818625907756, 36430.63569364379, 36991.8150409257, 37050.444243335834, 30973.845130775102, 33047.50428743619, 33334.49998250077, 33485.33029339448, 33661.663383365856, 24782.38044048913, 24869.25392238236, 25245.873770631028, 25293.747062529874, 25690.871545642152], "value": 16446.02898598371, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.99487007694876, 360.54211151837166, 364.2278369855906, 370.2003672068332, 383.06149932664977, 12452.626421207367, 16336.346546138155, 16782.496525104252, 16799.83200167998, 17900.0, 16644.1677916104, 17042.233099510522, 17143.828561714377, 17346.739798903025, 17497.0, 5003.974980125095, 5053.5451809337155, 5135.768890399922, 5157.355330583677, 5160.8709782255555, 3757.4176002719623, 5071.56891664209, 5157.71632560209, 5666.348369937468, 6095.451409373156, 6030.427109424598, 6121.571489995702, 6131.693415329238, 6131.7240724167295, 6132.693365331738, 5932.347441781422, 6010.9098363524145, 6021.157038014681, 6035.668038257894, 6047.909281360783, 5106.463821298785, 5300.072487314743, 5316.0, 5318.335208098988, 5325.281087053237, 20922.0, 21437.462818635064, 21440.14239430421, 21457.5708485831, 21567.24514641996, 1876.352658332873, 1961.4900125967351, 1996.1316827180078, 2113.8837363944976, 2154.762976072631, 13220.6694832629, 13305.600831975056, 13489.662758431117, 13492.797608035888, 14071.015028947977, 1700.2688843797162, 1730.5154556724137, 1740.4865564658455, 1755.1295155942234, 1777.1025632055857, 67419.0, 73195.0, 74466.25533744648, 74744.0, 74886.62556687236, 9574.542510768795, 9679.225661947028, 9898.30711850178, 10045.849312260321, 10067.1442927352, 13435.12671676338, 13448.0, 13455.932720336335, 13468.663283417993, 13490.0, 7547.773566793005, 7814.10137834145, 7818.8827167592535, 7949.284564389232, 8020.679172833022, 7673.424493163036, 7723.420743444264, 7728.111267204344, 7912.960435197787, 7987.760367188994, 5942.405759424023, 6118.265808102967, 6206.286277078104, 6276.823768767342, 6414.422701956846, 1741.3644019932633, 1744.5289771761554, 1771.973420398695, 1774.254812978552, 1795.3357257814691, 830.3855147191156, 840.2731637133958, 866.5970323799463, 873.8732883731823, 886.5611522296505, 18094.45716628477, 18106.728399074025, 18818.435446936615, 19033.90483047576, 19160.0, 834.3158609939912, 834.6828205282076, 840.4117118017413, 873.4628203654706, 937.6952490440634, 9357.298202634829, 10619.706282457413, 10622.203334749925, 10635.415052172204, 10733.892661073445, 387896.0, 398061.0, 399824.0, 400231.0, 400415.95840415725, 81145.59427202944, 81719.59140204261, 81865.59067204625, 86031.0, 86383.0, 881.405051590174, 906.8141031088611, 908.8863892013499, 909.5088652127777, 933.9813203735887, 15884.682306353578, 15989.360425583312, 16554.503364899072, 16577.254023568727, 16651.41720039793, 36217.27565448729, 36586.268274633825, 37419.97343267741, 39468.80265598654, 42156.15687686291, 50548.24177637265, 53492.0, 53956.0, 54193.45806542042, 54457.72771136119, 7335.7432489862595, 7499.175090740124, 7728.8454230916195, 7743.5353878767455, 7946.0, 10011.949940250252, 11679.649610511698, 12014.519419223134, 12092.214006089927, 12350.876491234972, 9784.60861565558, 9879.456629885426, 10247.897521024694, 10263.486825658538, 10589.311694739818, 10436.530356134144, 10440.843387349043, 10570.0, 10594.523246454082, 11063.612773553208, 9849.0, 10005.299629025898, 10168.847467288144, 10308.659874216304, 10500.369977801356, 6772.93227067723, 6962.895556566555, 7060.082189315356, 7245.818854528679, 8091.029076510857, 8196.877046844422, 9572.617095316109, 9747.0, 10304.124149447382, 10437.0], "value": 8533.37306428921, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1266.6453393049937, 1337.511808190011, 1398.9090709103907, 1434.906731062481, 1659.518739565526, 6745.5615385, 7539.962300188498, 7767.766966990986, 7793.415493837956, 8004.0, 7839.529628222311, 7895.368370530358, 7899.4865333753405, 7903.525788452697, 7907.683692652301, 2146.763855975845, 2171.1206961180746, 2172.0660116150048, 2172.326578760589, 2175.3147758456034, 2105.6841473778904, 2145.3027765976044, 2162.016282591417, 2163.1996161420298, 2163.7836216378314, 2113.7991890770354, 2138.080625331107, 2144.603248399051, 2144.6246906791325, 2146.677998300252, 1919.89440580768, 1923.0480911948605, 1923.9230430782752, 1929.527265819883, 1929.7491326127654, 1996.5707372914749, 2031.8476114291486, 2061.1446249806313, 2063.515073957634, 2066.617675729995, 7840.059192896889, 7850.293473587348, 7888.25061619151, 7915.12933577306, 7925.247101525403, 4489.820407183709, 4626.560476754703, 4633.745144017078, 4634.490206077328, 4639.512851150615, 2765.2395591212576, 2792.3158826087533, 2877.8848846046135, 2884.7210703544174, 2895.348546577003, 909.9909000910038, 914.1909410172045, 934.4720233068363, 940.6566603189787, 941.9340646154772, 10519.684409467729, 10739.62411315608, 10741.731456713567, 10746.462676866126, 10754.408507532082, 4090.529589097292, 4176.2900306947895, 4249.3413520904305, 4269.466316710411, 4270.466191726035, 9216.262698984065, 9300.348975571645, 9302.0, 9309.627614895327, 9311.580978855967, 4746.976265118652, 4752.833650822205, 4755.476897541268, 4758.5241475852135, 4761.35721677576, 3712.1833196696684, 4121.423000779893, 4250.617444429954, 4336.392904993303, 4337.306031035082, 3612.6748592626263, 3632.073821175636, 3766.886993390203, 3776.811159442017, 3806.410006449005, 1135.3528488761326, 1137.6871360375796, 1144.862616486027, 1145.7651181507772, 1159.0322081062238, 833.2750507058777, 842.9199226073513, 844.8099177685094, 860.3805260212637, 876.5047748022347, 5131.461196574381, 5147.0992576298995, 5318.095923692904, 5342.519173274424, 5428.945710542844, 378.1661436532439, 400.4593798372174, 404.7793952296006, 405.06026019634163, 408.93457046872356, 3561.8753343633366, 3686.9262614747554, 3730.925381492355, 3738.1962877981364, 3862.9034274143373, 9455.763605909908, 9815.509224538744, 9876.555555000043, 9883.0, 9906.306558540833, 9791.755206119904, 9810.950945245228, 9837.508124593885, 9844.310898237198, 9848.753781155385, 7001.054857594159, 7120.0, 7234.457415693844, 7242.275772422821, 7245.89131163033, 5126.719768283822, 5262.500062494057, 5273.077211488051, 5314.734263286743, 5459.781608735765, 8982.0, 9035.186833185046, 9040.909590904006, 9062.184403403726, 9075.183233509017, 8609.526476043879, 8952.597133128895, 8973.37186396972, 9145.451272923647, 9286.442813430946, 5898.144769008555, 6344.206974128234, 6466.935330646634, 6602.042703807825, 6608.570442921195, 7037.7184912602925, 7271.127464704269, 7277.0, 7304.269573042653, 7314.96342518284, 6525.608463492205, 6529.67351632407, 6584.703688333941, 6705.932940670531, 6735.730570777114, 6167.722452489738, 6256.937430625636, 6304.621722696653, 6406.878796210738, 6441.420272175526, 6196.876062478635, 6312.495000400048, 6312.652804095819, 6313.40022697843, 6322.525810564134, 4172.582741725803, 4203.390508376207, 4220.303649897733, 4302.268614335508, 4397.36238245459, 4574.176648203355, 4574.405327307429, 4610.377599024089, 4622.953770462387, 4718.245080787195], "value": 4013.0116003417293, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.8879070740479, 294.3817982237302, 304.1908523327947, 305.9204606802231, 306.50040434092426, 4653.720776753389, 4744.027474367755, 4768.213244814601, 4829.855104346871, 4839.903201935959, 4636.837710680127, 4752.334673145762, 4767.713937163772, 4773.260144677581, 4785.66500344976, 3780.4707340972286, 3786.8295926683363, 3798.3542797724385, 3810.485584446106, 3818.3127037133304, 3604.1710406606367, 3698.722595805312, 3786.413105968579, 3802.2205447883257, 3817.3892177251646, 3752.5684546277116, 3764.3600587899987, 3767.3972164453694, 3777.8299976501125, 3780.6786423154067, 3736.9626303737023, 3775.9622403775884, 3777.301199278142, 3784.8486060557543, 3785.4889589905288, 3354.9329013419597, 3359.8320083995704, 3364.2262279675565, 3369.8989030328885, 3372.207531230159, 4523.208438523246, 4601.976990115061, 4633.4208223972, 4645.558671926162, 4702.882427939295, 4265.594768496988, 4273.957260427418, 4274.5939135782055, 4284.914301713949, 4308.578169204186, 4975.328330675348, 4975.477574854624, 4982.352294201767, 4990.65065445419, 5029.245613158019, 3577.409727394977, 3580.785152890835, 3599.280143971216, 3653.5250417445563, 3678.6505281998434, 5165.793368265265, 5189.143791274435, 5205.583553315726, 5209.843704688866, 5232.215167724834, 3705.5368078990127, 3707.536557930259, 3716.4948195980132, 3722.9627703722617, 3738.1962877981364, 3787.6023017583307, 3794.867179648699, 3795.070207799052, 3796.5823759386444, 3808.790516521618, 4151.252774500558, 4192.098698779777, 4209.284421648327, 4235.2800023996, 4270.167317373144, 4951.306817045616, 4955.132851751001, 4972.279019542146, 4973.035650917867, 4986.32684587576, 1216.2763155922212, 1262.797952327623, 1266.5503746170161, 1291.9612411627666, 1298.3702904091508, 880.8238352329431, 895.9686410975585, 913.2556966072763, 923.1414784250711, 925.5187302602609, 4347.913041739147, 4359.716618419792, 4361.956380436219, 4364.8254069837485, 4366.118361881143, 5100.69395836251, 5104.753908280345, 5108.1865937592365, 5113.8721531961255, 5124.769385377667, 4343.261645520269, 4369.191699535532, 4370.7159034662645, 4382.430284063115, 4385.912281754411, 2280.247518318951, 2390.1237528540014, 2427.6358546218207, 2440.7193172785, 2448.6694296269775, 4264.722793018444, 4279.614834664895, 4327.2643650579475, 4358.498772641186, 4359.869203923888, 4621.976890115528, 4644.651651126179, 4652.976735116303, 4727.787249573708, 4742.762861856893, 4285.400043993844, 4493.662975276802, 4832.251001094836, 4864.759486330963, 4868.342773725572, 5193.662411943212, 5201.479852014768, 5214.817481388133, 5215.217717342429, 5217.373915130209, 5088.491150884882, 5111.718855462986, 5129.820456284012, 5146.948530514647, 5152.562032227303, 4679.859604211879, 4821.300911367762, 4830.154722923545, 4840.177169881058, 4847.151748443937, 3646.161382881899, 3671.963280367269, 3672.8163591820835, 3678.172411207457, 3690.870819521304, 4954.677945933503, 5212.452692467236, 5225.790968361223, 5228.542584985051, 5236.790528378822, 4875.658703890694, 5036.949630503648, 5038.622103342191, 5045.268436076675, 5060.949390506048, 5026.44709082008, 5032.27032080339, 5042.823501177441, 5061.544460998528, 5063.26582645507, 4795.20879054952, 4956.454789973172, 4960.677555958851, 4966.230234313687, 4979.0, 5054.393472783289, 5071.467495913023, 5098.5526947066355, 5103.566196873159, 5105.566026887607, 4990.950090499048, 4992.6255530834605, 4996.800127994839, 5000.274960130688, 5015.623828212826], "value": 3757.6032055083715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.9190783704058, 680.5780416141987, 688.8140202145421, 698.2458944340111, 702.1889717376432, 10710.625128120517, 12493.750124997507, 13407.86033187179, 13469.326533673315, 14627.853721462781, 13500.864991350083, 14012.0, 14139.293035348242, 14147.151170929752, 14151.504697335595, 771.7260372567728, 794.126460893016, 796.1163108949062, 796.3271035974622, 797.0594698256056, 633.1041576169714, 747.55146911853, 768.9269519395676, 769.6498093367533, 777.9105402878685, 827.9461834980738, 831.966721331146, 833.2833762963846, 835.2858306148247, 836.9246767790897, 824.3899514359331, 826.3265438667466, 826.4256341842422, 826.5949684654476, 826.5990994367727, 600.4655856287907, 600.4865839707031, 600.6486205569747, 603.873186630808, 606.3572613030202, 13447.462101516025, 13503.122297050759, 13509.121907076107, 13534.0, 13648.658783530393, 13042.608721738363, 13052.477900883954, 13176.802347964789, 13320.267385293804, 13416.0, 9983.800323993552, 9984.400935943866, 10029.849552256723, 10210.795784084277, 10407.0, 3187.0916788715335, 3206.422843888099, 3209.5667084943466, 3238.676132386766, 3256.1045712429036, 59431.10853337203, 59886.700566497326, 60248.698756506376, 61824.99999999955, 61933.69033154805, 10094.343867648578, 10114.393136411838, 10181.796364072825, 10260.486975651187, 10652.307600005975, 19158.904205478884, 19240.71138932917, 19243.0, 19244.326448574233, 19343.4196974091, 6637.435817955431, 6688.0, 6717.798466045929, 6851.2806155353965, 6906.827329316808, 3588.5155504006625, 3657.3599620066384, 3692.2800053989213, 3693.963060369416, 3752.2870654575545, 3323.11937336606, 3495.0563347896436, 3504.8247587620517, 3508.8070156141657, 3519.225770330523, 571.3743450921235, 571.5484767034079, 574.7413663851283, 577.7053702611614, 588.3351812451901, 552.6628756458557, 623.2956758862456, 623.6227082615044, 624.1418050181001, 625.524601303013, 7282.162551306562, 7314.341709246087, 7395.297446742551, 7594.696212151563, 7630.351420129351, 538.0798833993914, 551.0521902327989, 567.7728908436659, 577.4955348283373, 591.0, 3539.239063601338, 3613.8915832525067, 3704.7221458390727, 3744.9063773405883, 3800.1449673823167, 108741.0, 109951.45024274828, 110098.0, 110679.0, 113936.0, 94075.0, 94965.5251723737, 95619.0, 96218.0, 96482.51758741161, 78633.60683196547, 80676.99999999882, 81000.59499702464, 81024.0, 81910.0, 7054.365107140382, 7481.476296659399, 7555.433342499225, 7573.35626471756, 7922.326602238643, 31359.529607056367, 32130.83934580312, 40933.1813363737, 43436.348454772575, 44495.55504444914, 59585.40414595798, 61710.0, 62152.68923655353, 63709.36290637034, 64323.678381609665, 10838.837417438586, 11138.94430527842, 11169.497372618414, 11341.659750207507, 11450.656480305604, 12198.695032624255, 12340.50637974471, 12834.422450989541, 13436.93281533586, 13556.11885227457, 10527.315724477885, 10756.515956781806, 10767.461626918466, 10995.945020274847, 11403.543858245577, 10054.64808731691, 10128.746781330527, 10230.130438912776, 10397.896021039693, 10617.41604211745, 10409.791804163722, 10579.682609521727, 10663.8933610666, 10678.946605266923, 10729.463526823783, 9401.529923503935, 9950.502474876082, 10115.898841011496, 10286.485675716334, 10482.68551943443, 9017.504037278013, 9195.35632505745, 9339.906600933904, 9505.809883802423, 9524.095121000357], "value": 6549.161282993214, "unit": "score"}], "name": "dromaeo_css", "value": 6320.399046423066, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 114.0, 115.0, 117.0, 115.0, 114.0, 113.0, 114.0, 117.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 125.0, 184.0, 123.0, 127.0, 153.0, 123.0, 170.0, 123.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 162.0, 161.0, 161.0, 161.0, 162.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 125.0, 76.0, 77.0, 75.0, 76.0, 102.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 119.0, 85.0, 86.0, 86.0, 120.0, 85.0, 85.0, 134.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 145.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 70.0, 65.0, 65.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 79.0, 78.0, 79.0, 79.0, 78.0, 79.0, 79.0, 82.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 129.0, 128.0, 129.0, 138.0, 134.0, 127.0, 126.0, 184.0, 139.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 164.0, 165.0, 161.0, 165.0, 162.0, 163.0, 163.0, 163.0, 162.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 70.0, 65.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1527.6000000000001, "alertThreshold": 2.0}]} 04:06:42 INFO - Return code: 0 04:06:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:06:42 INFO - # TBPL SUCCESS # 04:06:42 INFO - Running post-action listener: _resource_record_post_action 04:06:42 INFO - [mozharness: 2016-07-22 11:06:42.748041Z] Finished run-tests step (success) 04:06:42 INFO - Running post-run listener: _resource_record_post_run 04:06:42 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 531755008; Read time: 12; Write time: 469936 04:06:42 INFO - TinderboxPrint: CPU usage
13.0% 04:06:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:06:42 INFO - TinderboxPrint: I/O write bytes / time
531,755,008 / 469,936 04:06:42 INFO - TinderboxPrint: CPU idle
8,338.7 (87.0%) 04:06:42 INFO - TinderboxPrint: CPU user
1,200.1 (12.5%) 04:06:42 INFO - TinderboxPrint: Swap in / out
0 / 0 04:06:42 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 242581504; Read time: 0; Write time: 238956 04:06:42 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 289173504; Read time: 0; Write time: 230980 04:06:43 INFO - Running post-run listener: _upload_blobber_files 04:06:43 INFO - Blob upload gear active. 04:06:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:06:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:06:43 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:06:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:06:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:44 INFO - (blobuploader) - INFO - Done attempting. 04:06:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:06:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:07:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:19 INFO - (blobuploader) - INFO - Done attempting. 04:07:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:07:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:07:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:19 INFO - (blobuploader) - INFO - Done attempting. 04:07:19 INFO - (blobuploader) - INFO - Iteration through files over. 04:07:19 INFO - Return code: 0 04:07:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:07:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:07:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bf72cbf4778079fba5fe7da198ed1fa51768f88d3203663f27b2e9ef713ad6f6effe1d8ad77a8c6513ed342cf1931d524b5cc15e59b305416306f3a09d5241b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61514c7a2e14456b4636763cc10e7a56c9cc5c3b0a61c771d796f389a032951218a45cac78ef32ec0b7fc5094b560236dee8ec8d3af5f9130033bae38d01724e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa65682dc24db8b55cb86190a85eb7439e8ad9749d1283b40f9d71d4a8489bf950cc821901dba89be2d68d2912f47d30f19ee86b9d1be27394c9a370569350dc"} 04:07:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:07:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:07:19 INFO - Contents: 04:07:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bf72cbf4778079fba5fe7da198ed1fa51768f88d3203663f27b2e9ef713ad6f6effe1d8ad77a8c6513ed342cf1931d524b5cc15e59b305416306f3a09d5241b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61514c7a2e14456b4636763cc10e7a56c9cc5c3b0a61c771d796f389a032951218a45cac78ef32ec0b7fc5094b560236dee8ec8d3af5f9130033bae38d01724e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa65682dc24db8b55cb86190a85eb7439e8ad9749d1283b40f9d71d4a8489bf950cc821901dba89be2d68d2912f47d30f19ee86b9d1be27394c9a370569350dc"} 04:07:19 INFO - Running post-run listener: copy_logs_to_upload_dir 04:07:19 INFO - Copying logs to upload dir... 04:07:19 INFO - mkdir: /builds/slave/test/build/upload/logs 04:07:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.548250 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-22 04:07:19.083255) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:19.087803) ========= 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=836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bf72cbf4778079fba5fe7da198ed1fa51768f88d3203663f27b2e9ef713ad6f6effe1d8ad77a8c6513ed342cf1931d524b5cc15e59b305416306f3a09d5241b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61514c7a2e14456b4636763cc10e7a56c9cc5c3b0a61c771d796f389a032951218a45cac78ef32ec0b7fc5094b560236dee8ec8d3af5f9130033bae38d01724e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa65682dc24db8b55cb86190a85eb7439e8ad9749d1283b40f9d71d4a8489bf950cc821901dba89be2d68d2912f47d30f19ee86b9d1be27394c9a370569350dc"} build_url:https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/SwJ6LmwQQsqpA-97QYenHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bf72cbf4778079fba5fe7da198ed1fa51768f88d3203663f27b2e9ef713ad6f6effe1d8ad77a8c6513ed342cf1931d524b5cc15e59b305416306f3a09d5241b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61514c7a2e14456b4636763cc10e7a56c9cc5c3b0a61c771d796f389a032951218a45cac78ef32ec0b7fc5094b560236dee8ec8d3af5f9130033bae38d01724e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa65682dc24db8b55cb86190a85eb7439e8ad9749d1283b40f9d71d4a8489bf950cc821901dba89be2d68d2912f47d30f19ee86b9d1be27394c9a370569350dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:19.114787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:19.115162) ========= 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=836cfdc75598d54cb747368e000001a9-1469184030.396744-1171719152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 04:07:19.201984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:07:19.202329) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 04:07:19.205742) ========= ========= Total master_lag: 0.19 =========