builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-010 starttime: 1469051637.44 results: success (0) buildid: 20160720133419 builduid: 5b0de22999fa422ba7da3205e23dbc77 revision: 36b524e69dc5250a87d8f6c42a369c817d508f39 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.444640) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.445126) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.445443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.464985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.465298) ========= 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=eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-20 14:53:57-- 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% 23.3M=0s 2016-07-20 14:53:57 (23.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104698 ========= master_lag: 0.37 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.942551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.942946) ========= 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=eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018575 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 14:53:57.978627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 14:53:57.979021) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 36b524e69dc5250a87d8f6c42a369c817d508f39 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 36b524e69dc5250a87d8f6c42a369c817d508f39 --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=eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-20 14:53:58,172 truncating revision to first 12 chars 2016-07-20 14:53:58,172 Setting DEBUG logging. 2016-07-20 14:53:58,172 attempt 1/10 2016-07-20 14:53:58,172 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/36b524e69dc5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 14:53:59,330 unpacking tar archive at: autoland-36b524e69dc5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.472169 ========= master_lag: 1.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 14:54:00.718794) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 14:54:00.719100) ========= script_repo_revision: 36b524e69dc5250a87d8f6c42a369c817d508f39 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 14:54:00.719487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 14:54:00.719766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 14:54:00.874396) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-07-20 14:54:00.874673) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:54:01 INFO - MultiFileLogger online at 20160720 14:54:01 in /builds/slave/test 14:54:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 14:54:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:54:01 INFO - {'append_to_log': False, 14:54:01 INFO - 'base_work_dir': '/builds/slave/test', 14:54:01 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 14:54:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:54:01 INFO - 'branch': 'Autoland-Non-PGO', 14:54:01 INFO - 'buildbot_json_path': 'buildprops.json', 14:54:01 INFO - 'config_files': ('talos/linux_config.py',), 14:54:01 INFO - 'default_actions': ('clobber', 14:54:01 INFO - 'read-buildbot-config', 14:54:01 INFO - 'download-and-extract', 14:54:01 INFO - 'populate-webroot', 14:54:01 INFO - 'create-virtualenv', 14:54:01 INFO - 'install', 14:54:01 INFO - 'run-tests'), 14:54:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:54:01 INFO - 'download_minidump_stackwalk': True, 14:54:01 INFO - 'download_symbols': 'ondemand', 14:54:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:54:01 INFO - 'tooltool.py': '/tools/tooltool.py', 14:54:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:54:01 INFO - '/tools/misc-python/virtualenv.py')}, 14:54:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:54:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:54:01 INFO - 'installer_path': 'installer.exe', 14:54:01 INFO - 'log_level': 'info', 14:54:01 INFO - 'log_name': 'talos', 14:54:01 INFO - 'log_to_console': True, 14:54:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:54:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:54:01 INFO - 'opt_config_files': (), 14:54:01 INFO - 'pip_index': False, 14:54:01 INFO - 'sps_profile': False, 14:54:01 INFO - 'sps_profile_interval': 0, 14:54:01 INFO - 'suite': 'dromaeojs', 14:54:01 INFO - 'system_bits': '32', 14:54:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:54:01 INFO - 'title': 'talos-linux64-ix-010', 14:54:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:54:01 INFO - 'use_talos_json': True, 14:54:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:54:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:54:01 INFO - 'work_dir': 'build'} 14:54:01 INFO - [mozharness: 2016-07-20 21:54:01.161725Z] Running clobber step. 14:54:01 INFO - Running pre-action listener: _resource_record_pre_action 14:54:01 INFO - Running main action method: clobber 14:54:01 INFO - rmtree: /builds/slave/test/build 14:54:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:54:01 INFO - Running post-action listener: _resource_record_post_action 14:54:01 INFO - [mozharness: 2016-07-20 21:54:01.671876Z] Finished clobber step (success) 14:54:01 INFO - [mozharness: 2016-07-20 21:54:01.671977Z] Running read-buildbot-config step. 14:54:01 INFO - Running pre-action listener: _resource_record_pre_action 14:54:01 INFO - Running main action method: read_buildbot_config 14:54:01 INFO - Using buildbot properties: 14:54:01 INFO - { 14:54:01 INFO - "project": "", 14:54:01 INFO - "product": "firefox", 14:54:01 INFO - "script_repo_revision": "production", 14:54:01 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 14:54:01 INFO - "repository": "", 14:54:01 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 14:54:01 INFO - "buildid": "20160720133419", 14:54:01 INFO - "pgo_build": "False", 14:54:01 INFO - "basedir": "/builds/slave/test", 14:54:01 INFO - "buildnumber": 195, 14:54:01 INFO - "slavename": "talos-linux64-ix-010", 14:54:01 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:54:01 INFO - "platform": "ubuntu64_hw", 14:54:01 INFO - "scheduler": "tests-autoland-linux64-talos", 14:54:01 INFO - "branch": "autoland", 14:54:01 INFO - "repo_path": "integration/autoland", 14:54:01 INFO - "revision": "36b524e69dc5250a87d8f6c42a369c817d508f39", 14:54:01 INFO - "stage_platform": "linux64", 14:54:01 INFO - "builduid": "5b0de22999fa422ba7da3205e23dbc77", 14:54:01 INFO - "slavebuilddir": "test" 14:54:01 INFO - } 14:54:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 14:54:01 INFO - Running post-action listener: _resource_record_post_action 14:54:01 INFO - [mozharness: 2016-07-20 21:54:01.674625Z] Finished read-buildbot-config step (success) 14:54:01 INFO - [mozharness: 2016-07-20 21:54:01.674715Z] Running download-and-extract step. 14:54:01 INFO - Running pre-action listener: _resource_record_pre_action 14:54:01 INFO - Running main action method: download_and_extract 14:54:01 INFO - mkdir: /builds/slave/test/build/tests 14:54: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')]} 14:54:01 INFO - https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:54:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:54:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:54:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:02 INFO - Downloaded 1361 bytes. 14:54:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:54:02 INFO - Using the following test package requirements: 14:54:02 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:54:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:54:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:54:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'jsshell-linux-x86_64.zip'], 14:54:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:54:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:54:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:54:02 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:54:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:54:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:54:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:54:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:02 INFO - https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:54:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:54:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:54:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:03 INFO - Downloaded 22730093 bytes. 14:54:03 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'] 14:54:03 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 14:54:04 INFO - Return code: 0 14:54:04 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 14:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:04 INFO - https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:54:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:54:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:54:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:06 INFO - Downloaded 22730093 bytes. 14:54:06 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'] 14:54:06 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 14:54:07 INFO - Return code: 0 14:54:07 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')]} 14:54:07 INFO - https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:54:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:54:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:54:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:09 INFO - Downloaded 14403317 bytes. 14:54:09 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'] 14:54:09 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 14:54:09 INFO - Return code: 0 14:54:09 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')]} 14:54:09 INFO - https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:54:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:54:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:54:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dO7BcR5xRniIn3ByD2kaRg/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 14:54:10 INFO - Downloaded 57145064 bytes. 14:54:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:54:10 INFO - mkdir: /builds/slave/test/properties 14:54:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:54:10 INFO - Writing to file /builds/slave/test/properties/build_url 14:54:10 INFO - Contents: 14:54:10 INFO - build_url:https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:54:11 INFO - Running post-action listener: _resource_record_post_action 14:54:11 INFO - Running post-action listener: set_extra_try_arguments 14:54:11 INFO - [mozharness: 2016-07-20 21:54:11.611008Z] Finished download-and-extract step (success) 14:54:11 INFO - [mozharness: 2016-07-20 21:54:11.611329Z] Running populate-webroot step. 14:54:11 INFO - Running pre-action listener: _resource_record_pre_action 14:54:11 INFO - Running main action method: populate_webroot 14:54:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:54:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:54:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:54:11 INFO - '--tppagecycles', 14:54:11 INFO - '7'], 14:54:11 INFO - 'tests': ['tsvgm']}}, 14:54:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:54:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:54:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:54:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:54:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['damp', 'tps']}, 14:54:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'tests': ['damp', 'tps']}, 14:54:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['dromaeo_dom']}, 14:54:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:54:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['basic_compositor_video']}, 14:54:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:54:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['a11yr', 14:54:11 INFO - 'ts_paint', 14:54:11 INFO - 'tpaint', 14:54:11 INFO - 'sessionrestore', 14:54:11 INFO - 'sessionrestore_no_auto_restore', 14:54:11 INFO - 'tabpaint']}, 14:54:11 INFO - 'other-e10s': {'tests': ['a11yr', 14:54:11 INFO - 'ts_paint', 14:54:11 INFO - 'tpaint', 14:54:11 INFO - 'sessionrestore', 14:54:11 INFO - 'sessionrestore_no_auto_restore', 14:54:11 INFO - 'tabpaint']}, 14:54:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:54:11 INFO - 'ts_paint', 14:54:11 INFO - 'tpaint', 14:54:11 INFO - 'sessionrestore', 14:54:11 INFO - 'sessionrestore_no_auto_restore', 14:54:11 INFO - 'tabpaint']}, 14:54:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:54:11 INFO - 'ts_paint', 14:54:11 INFO - 'tpaint', 14:54:11 INFO - 'sessionrestore', 14:54:11 INFO - 'sessionrestore_no_auto_restore', 14:54:11 INFO - 'tabpaint']}, 14:54:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['a11yr', 14:54:11 INFO - 'ts_paint', 14:54:11 INFO - 'tpaint', 14:54:11 INFO - 'sessionrestore', 14:54:11 INFO - 'sessionrestore_no_auto_restore', 14:54:11 INFO - 'tabpaint']}, 14:54:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['a11yr', 14:54:11 INFO - 'ts_paint', 14:54:11 INFO - 'tpaint', 14:54:11 INFO - 'sessionrestore', 14:54:11 INFO - 'sessionrestore_no_auto_restore', 14:54:11 INFO - 'tabpaint']}, 14:54:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['tsvgx', 14:54:11 INFO - 'tsvgr_opacity', 14:54:11 INFO - 'tart', 14:54:11 INFO - 'tscrollx', 14:54:11 INFO - 'cart']}, 14:54:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:54:11 INFO - 'tsvgr_opacity', 14:54:11 INFO - 'tart', 14:54:11 INFO - 'tscrollx', 14:54:11 INFO - 'cart']}, 14:54:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'talos_options': ['--disable-e10s'], 14:54:11 INFO - 'tests': ['tp5o']}, 14:54:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'tests': ['tp5o']}, 14:54:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'talos_options': ['--disable-e10s', 14:54:11 INFO - '--xperf_path', 14:54:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:11 INFO - 'tests': ['tp5n']}, 14:54:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:11 INFO - 'talos_options': ['--xperf_path', 14:54:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:11 INFO - 'tests': ['tp5n']}}, 14:54:11 INFO - 'talos.zip': {'path': '', 14:54:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:54:11 INFO - Running post-action listener: _resource_record_post_action 14:54:11 INFO - [mozharness: 2016-07-20 21:54:11.627612Z] Finished populate-webroot step (success) 14:54:11 INFO - [mozharness: 2016-07-20 21:54:11.627701Z] Running create-virtualenv step. 14:54:11 INFO - Running pre-action listener: _resource_record_pre_action 14:54:11 INFO - Running main action method: create_virtualenv 14:54:11 INFO - Creating virtualenv /builds/slave/test/build/venv 14:54:11 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 14:54:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:54:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:54:11 INFO - Using real prefix '/usr' 14:54:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:54:12 INFO - Installing distribute.............................................................................................................................................................................................done. 14:54:14 INFO - Installing pip.................done. 14:54:14 INFO - Return code: 0 14:54:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:54:14 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')]} 14:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:14 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')]} 14:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:14 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:14 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 14:54:14 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 14:54:14 INFO - Using env: {'DISPLAY': ':0', 14:54:14 INFO - 'HOME': '/home/cltbld', 14:54:14 INFO - 'LANG': 'en_US.UTF-8', 14:54:14 INFO - 'LANGUAGE': 'en_US:en', 14:54:14 INFO - 'LOGNAME': 'cltbld', 14:54:14 INFO - 'MAIL': '/var/mail/cltbld', 14:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:14 INFO - 'MOZ_NO_REMOTE': '1', 14:54:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:14 INFO - 'NO_EM_RESTART': '1', 14:54:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:14 INFO - 'PWD': '/builds/slave/test', 14:54:14 INFO - 'SHELL': '/bin/bash', 14:54:14 INFO - 'SHLVL': '1', 14:54:14 INFO - 'TERM': 'linux', 14:54:14 INFO - 'TMOUT': '86400', 14:54:14 INFO - 'USER': 'cltbld', 14:54:14 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:14 INFO - '_': '/tools/buildbot/bin/python'} 14:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:14 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 14:54:14 INFO - Cleaning up... 14:54:14 INFO - Return code: 0 14:54:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:54:14 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')]} 14:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:14 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')]} 14:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:14 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:14 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 14:54:14 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 14:54:14 INFO - Using env: {'DISPLAY': ':0', 14:54:14 INFO - 'HOME': '/home/cltbld', 14:54:14 INFO - 'LANG': 'en_US.UTF-8', 14:54:14 INFO - 'LANGUAGE': 'en_US:en', 14:54:14 INFO - 'LOGNAME': 'cltbld', 14:54:14 INFO - 'MAIL': '/var/mail/cltbld', 14:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:14 INFO - 'MOZ_NO_REMOTE': '1', 14:54:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:14 INFO - 'NO_EM_RESTART': '1', 14:54:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:14 INFO - 'PWD': '/builds/slave/test', 14:54:14 INFO - 'SHELL': '/bin/bash', 14:54:14 INFO - 'SHLVL': '1', 14:54:14 INFO - 'TERM': 'linux', 14:54:14 INFO - 'TMOUT': '86400', 14:54:14 INFO - 'USER': 'cltbld', 14:54:14 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:14 INFO - '_': '/tools/buildbot/bin/python'} 14:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:14 INFO - Downloading/unpacking psutil>=3.1.1 14:54:14 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 14:54:14 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 14:54:14 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 14:54:14 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 14:54:14 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 14:54:14 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 14:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:54:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:16 INFO - Installing collected packages: psutil 14:54:16 INFO - Running setup.py install for psutil 14:54:16 INFO - building 'psutil._psutil_linux' extension 14:54:16 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 14:54:16 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 14:54:16 INFO - building 'psutil._psutil_posix' extension 14:54:16 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 14:54:17 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 14:54:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:17 INFO - Successfully installed psutil 14:54:17 INFO - Cleaning up... 14:54:17 INFO - Return code: 0 14:54:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:17 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:17 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 14:54:17 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 14:54:17 INFO - Using env: {'DISPLAY': ':0', 14:54:17 INFO - 'HOME': '/home/cltbld', 14:54:17 INFO - 'LANG': 'en_US.UTF-8', 14:54:17 INFO - 'LANGUAGE': 'en_US:en', 14:54:17 INFO - 'LOGNAME': 'cltbld', 14:54:17 INFO - 'MAIL': '/var/mail/cltbld', 14:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:17 INFO - 'MOZ_NO_REMOTE': '1', 14:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:17 INFO - 'NO_EM_RESTART': '1', 14:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:17 INFO - 'PWD': '/builds/slave/test', 14:54:17 INFO - 'SHELL': '/bin/bash', 14:54:17 INFO - 'SHLVL': '1', 14:54:17 INFO - 'TERM': 'linux', 14:54:17 INFO - 'TMOUT': '86400', 14:54:17 INFO - 'USER': 'cltbld', 14:54:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:17 INFO - '_': '/tools/buildbot/bin/python'} 14:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:54:17 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 14:54:17 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 14:54:17 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 14:54:17 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 14:54:17 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 14:54:17 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 14:54:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:54:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:54:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:54:19 INFO - Installing collected packages: mozsystemmonitor 14:54:19 INFO - Running setup.py install for mozsystemmonitor 14:54:19 INFO - Successfully installed mozsystemmonitor 14:54:19 INFO - Cleaning up... 14:54:19 INFO - Return code: 0 14:54:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:19 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:19 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 14:54:19 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 14:54:19 INFO - Using env: {'DISPLAY': ':0', 14:54:19 INFO - 'HOME': '/home/cltbld', 14:54:19 INFO - 'LANG': 'en_US.UTF-8', 14:54:19 INFO - 'LANGUAGE': 'en_US:en', 14:54:19 INFO - 'LOGNAME': 'cltbld', 14:54:19 INFO - 'MAIL': '/var/mail/cltbld', 14:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:19 INFO - 'MOZ_NO_REMOTE': '1', 14:54:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:19 INFO - 'NO_EM_RESTART': '1', 14:54:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:19 INFO - 'PWD': '/builds/slave/test', 14:54:19 INFO - 'SHELL': '/bin/bash', 14:54:19 INFO - 'SHLVL': '1', 14:54:19 INFO - 'TERM': 'linux', 14:54:19 INFO - 'TMOUT': '86400', 14:54:19 INFO - 'USER': 'cltbld', 14:54:19 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:19 INFO - '_': '/tools/buildbot/bin/python'} 14:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:19 INFO - Downloading/unpacking jsonschema==2.5.1 14:54:19 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 14:54:19 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 14:54:19 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 14:54:19 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 14:54:19 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 14:54:19 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 14:54:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:54:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:54:21 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 14:54:21 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 14:54:21 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 14:54:21 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 14:54:21 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 14:54:21 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 14:54:21 INFO - Downloading functools32-3.2.3-2.tar.gz 14:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:54:21 INFO - warning: no files found matching '*.txt' 14:54:21 INFO - no previously-included directories found matching 'build' 14:54:21 INFO - no previously-included directories found matching 'dist' 14:54:21 INFO - no previously-included directories found matching '.git*' 14:54:21 INFO - Installing collected packages: jsonschema, functools32 14:54:21 INFO - Running setup.py install for functools32 14:54:22 INFO - warning: no files found matching '*.txt' 14:54:22 INFO - no previously-included directories found matching 'build' 14:54:22 INFO - no previously-included directories found matching 'dist' 14:54:22 INFO - no previously-included directories found matching '.git*' 14:54:22 INFO - Successfully installed jsonschema functools32 14:54:22 INFO - Cleaning up... 14:54:22 INFO - Return code: 0 14:54:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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', '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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:54: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 blobuploader==1.2.4 14:54:22 INFO - Using env: {'DISPLAY': ':0', 14:54:22 INFO - 'HOME': '/home/cltbld', 14:54:22 INFO - 'LANG': 'en_US.UTF-8', 14:54:22 INFO - 'LANGUAGE': 'en_US:en', 14:54:22 INFO - 'LOGNAME': 'cltbld', 14:54:22 INFO - 'MAIL': '/var/mail/cltbld', 14:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:22 INFO - 'MOZ_NO_REMOTE': '1', 14:54:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:22 INFO - 'NO_EM_RESTART': '1', 14:54:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:22 INFO - 'PWD': '/builds/slave/test', 14:54:22 INFO - 'SHELL': '/bin/bash', 14:54:22 INFO - 'SHLVL': '1', 14:54:22 INFO - 'TERM': 'linux', 14:54:22 INFO - 'TMOUT': '86400', 14:54:22 INFO - 'USER': 'cltbld', 14:54:22 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:22 INFO - '_': '/tools/buildbot/bin/python'} 14:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:22 INFO - Downloading/unpacking blobuploader==1.2.4 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54:24 INFO - Downloading blobuploader-1.2.4.tar.gz 14:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:54:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:54:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 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 14:54:24 INFO - Downloading docopt-0.6.1.tar.gz 14:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:54:25 INFO - Installing collected packages: blobuploader, requests, docopt 14:54:25 INFO - Running setup.py install for blobuploader 14:54:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:54:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:54:25 INFO - Running setup.py install for requests 14:54:25 INFO - Running setup.py install for docopt 14:54:25 INFO - Successfully installed blobuploader requests docopt 14:54:25 INFO - Cleaning up... 14:54:25 INFO - Return code: 0 14:54:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:25 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:25 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 14:54:25 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 14:54:25 INFO - Using env: {'DISPLAY': ':0', 14:54:25 INFO - 'HOME': '/home/cltbld', 14:54:25 INFO - 'LANG': 'en_US.UTF-8', 14:54:25 INFO - 'LANGUAGE': 'en_US:en', 14:54:25 INFO - 'LOGNAME': 'cltbld', 14:54:25 INFO - 'MAIL': '/var/mail/cltbld', 14:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:25 INFO - 'MOZ_NO_REMOTE': '1', 14:54:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:25 INFO - 'NO_EM_RESTART': '1', 14:54:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:25 INFO - 'PWD': '/builds/slave/test', 14:54:25 INFO - 'SHELL': '/bin/bash', 14:54:25 INFO - 'SHLVL': '1', 14:54:25 INFO - 'TERM': 'linux', 14:54:25 INFO - 'TMOUT': '86400', 14:54:25 INFO - 'USER': 'cltbld', 14:54:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:25 INFO - '_': '/tools/buildbot/bin/python'} 14:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:25 INFO - Running setup.py (path:/tmp/pip-IOHQfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:25 INFO - Running setup.py (path:/tmp/pip-7Am86m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:26 INFO - Running setup.py (path:/tmp/pip-2Qo18n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:26 INFO - Running setup.py (path:/tmp/pip-RZIqFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:26 INFO - Running setup.py (path:/tmp/pip-LBK9xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:26 INFO - Running setup.py (path:/tmp/pip-YZ4U9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:26 INFO - Running setup.py (path:/tmp/pip-wnBEKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:26 INFO - Running setup.py (path:/tmp/pip-UhQJ0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:26 INFO - Running setup.py (path:/tmp/pip-mu6tFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:26 INFO - Running setup.py (path:/tmp/pip-H2z175-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:26 INFO - Running setup.py (path:/tmp/pip-cIta0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:26 INFO - Running setup.py (path:/tmp/pip-fDeaq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:26 INFO - Running setup.py (path:/tmp/pip-SPhG51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:26 INFO - Running setup.py (path:/tmp/pip-Bf41HU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:26 INFO - Running setup.py (path:/tmp/pip-KFu9UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:26 INFO - Running setup.py (path:/tmp/pip-PGoxsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:27 INFO - Running setup.py (path:/tmp/pip-SRDfOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:54:27 INFO - Running setup.py install for manifestparser 14:54:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Running setup.py install for mozcrash 14:54:27 INFO - Running setup.py install for mozdebug 14:54:27 INFO - Running setup.py install for mozdevice 14:54:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Running setup.py install for mozfile 14:54:27 INFO - Running setup.py install for mozhttpd 14:54:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Running setup.py install for mozinfo 14:54:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Running setup.py install for mozInstall 14:54:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:54:27 INFO - Running setup.py install for mozleak 14:54:27 INFO - Running setup.py install for mozlog 14:54:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Running setup.py install for moznetwork 14:54:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Running setup.py install for mozprocess 14:54:28 INFO - Running setup.py install for mozprofile 14:54:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Running setup.py install for mozrunner 14:54:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Running setup.py install for mozscreenshot 14:54:28 INFO - Running setup.py install for moztest 14:54:28 INFO - Running setup.py install for mozversion 14:54:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:54:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:54:28 INFO - Cleaning up... 14:54:28 INFO - Return code: 0 14:54:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:28 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:28 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 14:54:28 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 14:54:28 INFO - Using env: {'DISPLAY': ':0', 14:54:28 INFO - 'HOME': '/home/cltbld', 14:54:28 INFO - 'LANG': 'en_US.UTF-8', 14:54:28 INFO - 'LANGUAGE': 'en_US:en', 14:54:28 INFO - 'LOGNAME': 'cltbld', 14:54:28 INFO - 'MAIL': '/var/mail/cltbld', 14:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:28 INFO - 'MOZ_NO_REMOTE': '1', 14:54:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:28 INFO - 'NO_EM_RESTART': '1', 14:54:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:28 INFO - 'PWD': '/builds/slave/test', 14:54:28 INFO - 'SHELL': '/bin/bash', 14:54:28 INFO - 'SHLVL': '1', 14:54:28 INFO - 'TERM': 'linux', 14:54:28 INFO - 'TMOUT': '86400', 14:54:28 INFO - 'USER': 'cltbld', 14:54:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:28 INFO - '_': '/tools/buildbot/bin/python'} 14:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:29 INFO - Running setup.py (path:/tmp/pip-Uw0Zmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:29 INFO - Running setup.py (path:/tmp/pip-4Smf_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:29 INFO - Running setup.py (path:/tmp/pip-ijzIgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:29 INFO - Running setup.py (path:/tmp/pip-ioBPVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:29 INFO - Running setup.py (path:/tmp/pip-b6_ins-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:29 INFO - Running setup.py (path:/tmp/pip-W1LgNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:29 INFO - Running setup.py (path:/tmp/pip-az3TXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:29 INFO - Running setup.py (path:/tmp/pip-_4FBb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:29 INFO - Running setup.py (path:/tmp/pip-V7cV4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:29 INFO - Running setup.py (path:/tmp/pip-I2ndjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:29 INFO - Running setup.py (path:/tmp/pip-nRXmXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:29 INFO - Running setup.py (path:/tmp/pip-CnG4vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:29 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)) 14:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:29 INFO - Running setup.py (path:/tmp/pip-PIbHDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:30 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)) 14:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:30 INFO - Running setup.py (path:/tmp/pip-qdpBbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:30 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)) 14:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:30 INFO - Running setup.py (path:/tmp/pip-3cq67P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:30 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)) 14:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:30 INFO - Running setup.py (path:/tmp/pip-rzjMgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:30 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)) 14:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:30 INFO - Running setup.py (path:/tmp/pip-wsZXdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:30 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)) 14:54:30 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)) 14:54:30 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)) 14:54:30 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)) 14:54:30 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)) 14:54:30 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)) 14:54:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54:32 INFO - Downloading blessings-1.6.tar.gz 14:54:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:54:32 INFO - Installing collected packages: blessings 14:54:32 INFO - Running setup.py install for blessings 14:54:32 INFO - Successfully installed blessings 14:54:32 INFO - Cleaning up... 14:54:32 INFO - Return code: 0 14:54:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:54:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:32 INFO - Reading from file tmpfile_stdout 14:54:32 INFO - Current package versions: 14:54:32 INFO - argparse == 1.2.1 14:54:32 INFO - blessings == 1.6 14:54:32 INFO - blobuploader == 1.2.4 14:54:32 INFO - docopt == 0.6.1 14:54:32 INFO - functools32 == 3.2.3-2 14:54:32 INFO - jsonschema == 2.5.1 14:54:32 INFO - manifestparser == 1.1 14:54:32 INFO - mozInstall == 1.12 14:54:32 INFO - mozcrash == 0.17 14:54:32 INFO - mozdebug == 0.1 14:54:32 INFO - mozdevice == 0.48 14:54:32 INFO - mozfile == 1.2 14:54:32 INFO - mozhttpd == 0.7 14:54:32 INFO - mozinfo == 0.9 14:54:32 INFO - mozleak == 0.1 14:54:32 INFO - mozlog == 3.3 14:54:32 INFO - moznetwork == 0.27 14:54:32 INFO - mozprocess == 0.23 14:54:32 INFO - mozprofile == 0.28 14:54:32 INFO - mozrunner == 6.12 14:54:32 INFO - mozscreenshot == 0.1 14:54:32 INFO - mozsystemmonitor == 0.3 14:54:32 INFO - moztest == 0.7 14:54:32 INFO - mozversion == 1.4 14:54:32 INFO - psutil == 3.1.1 14:54:32 INFO - requests == 1.2.3 14:54:32 INFO - wsgiref == 0.1.2 14:54:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:32 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 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:32 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 14:54:32 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 14:54:32 INFO - Using env: {'DISPLAY': ':0', 14:54:32 INFO - 'HOME': '/home/cltbld', 14:54:32 INFO - 'LANG': 'en_US.UTF-8', 14:54:32 INFO - 'LANGUAGE': 'en_US:en', 14:54:32 INFO - 'LOGNAME': 'cltbld', 14:54:32 INFO - 'MAIL': '/var/mail/cltbld', 14:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:32 INFO - 'MOZ_NO_REMOTE': '1', 14:54:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:32 INFO - 'NO_EM_RESTART': '1', 14:54:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:32 INFO - 'PWD': '/builds/slave/test', 14:54:32 INFO - 'SHELL': '/bin/bash', 14:54:32 INFO - 'SHLVL': '1', 14:54:32 INFO - 'TERM': 'linux', 14:54:32 INFO - 'TMOUT': '86400', 14:54:32 INFO - 'USER': 'cltbld', 14:54:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:32 INFO - '_': '/tools/buildbot/bin/python'} 14:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 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)) 14:54:32 INFO - Cleaning up... 14:54:32 INFO - Return code: 0 14:54:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, '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': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', '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 14:54:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:54:32 INFO - Using env: {'DISPLAY': ':0', 14:54:32 INFO - 'HOME': '/home/cltbld', 14:54:32 INFO - 'LANG': 'en_US.UTF-8', 14:54:32 INFO - 'LANGUAGE': 'en_US:en', 14:54:32 INFO - 'LOGNAME': 'cltbld', 14:54:32 INFO - 'MAIL': '/var/mail/cltbld', 14:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:32 INFO - 'MOZ_NO_REMOTE': '1', 14:54:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:32 INFO - 'NO_EM_RESTART': '1', 14:54:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:32 INFO - 'PWD': '/builds/slave/test', 14:54:32 INFO - 'SHELL': '/bin/bash', 14:54:32 INFO - 'SHLVL': '1', 14:54:32 INFO - 'TERM': 'linux', 14:54:32 INFO - 'TMOUT': '86400', 14:54:32 INFO - 'USER': 'cltbld', 14:54:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:32 INFO - '_': '/tools/buildbot/bin/python'} 14:54:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:54:33 INFO - Cleaning up... 14:54:33 INFO - Return code: 0 14:54:33 INFO - Running post-action listener: _resource_record_post_action 14:54:33 INFO - Running post-action listener: _start_resource_monitoring 14:54:33 INFO - Starting resource monitoring. 14:54:33 INFO - [mozharness: 2016-07-20 21:54:33.157303Z] Finished create-virtualenv step (success) 14:54:33 INFO - [mozharness: 2016-07-20 21:54:33.157741Z] Running install step. 14:54:33 INFO - Running pre-action listener: _resource_record_pre_action 14:54:33 INFO - Running main action method: install 14:54:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:33 INFO - Reading from file tmpfile_stdout 14:54:33 INFO - Detecting whether we're running mozinstall >=1.0... 14:54:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:54:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:54:33 INFO - Reading from file tmpfile_stdout 14:54:33 INFO - Output received: 14:54:33 INFO - Usage: mozinstall [options] installer 14:54:33 INFO - Options: 14:54:33 INFO - -h, --help show this help message and exit 14:54:33 INFO - -d DEST, --destination=DEST 14:54:33 INFO - Directory to install application into. [default: 14:54:33 INFO - "/builds/slave/test"] 14:54:33 INFO - --app=APP Application being installed. [default: firefox] 14:54:33 INFO - mkdir: /builds/slave/test/build/application 14:54:33 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'] 14:54:33 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 14:54:49 INFO - Reading from file tmpfile_stdout 14:54:49 INFO - Output received: 14:54:49 INFO - /builds/slave/test/build/application/firefox/firefox 14:54:49 INFO - Running post-action listener: _resource_record_post_action 14:54:49 INFO - [mozharness: 2016-07-20 21:54:49.827634Z] Finished install step (success) 14:54:49 INFO - [mozharness: 2016-07-20 21:54:49.827911Z] Running run-tests step. 14:54:49 INFO - Running pre-action listener: _resource_record_pre_action 14:54:49 INFO - Running main action method: run_tests 14:54:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:54:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:54:49 INFO - Python 2.7.3 14:54:49 INFO - Return code: 0 14:54:49 INFO - grabbing minidump binary from tooltool 14:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:49 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 0x1c5b3b0>, '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 0x1c5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:54:49 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 14:54:49 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 14:54:49 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 14:54:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:54:49 INFO - Return code: 0 14:54:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:54:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:54:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:54:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:54:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:54:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 14:54:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 14:54:49 INFO - Using env: {'DISPLAY': ':0', 14:54:49 INFO - 'HOME': '/home/cltbld', 14:54:49 INFO - 'LANG': 'en_US.UTF-8', 14:54:49 INFO - 'LANGUAGE': 'en_US:en', 14:54:49 INFO - 'LOGNAME': 'cltbld', 14:54:49 INFO - 'MAIL': '/var/mail/cltbld', 14:54:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:54:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:49 INFO - 'MOZ_NO_REMOTE': '1', 14:54:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:49 INFO - 'NO_EM_RESTART': '1', 14:54:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:49 INFO - 'PWD': '/builds/slave/test', 14:54:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:54:49 INFO - 'SHELL': '/bin/bash', 14:54:49 INFO - 'SHLVL': '1', 14:54:49 INFO - 'TERM': 'linux', 14:54:49 INFO - 'TMOUT': '86400', 14:54:49 INFO - 'USER': 'cltbld', 14:54:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415', 14:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:49 INFO - '_': '/tools/buildbot/bin/python'} 14:54:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 14:54:50 INFO - mozversion application_buildid: 20160720133419 14:54:50 INFO - mozversion application_changeset: 36b524e69dc5250a87d8f6c42a369c817d508f39 14:54:50 INFO - mozversion application_display_name: Nightly 14:54:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:54:50 INFO - mozversion application_name: Firefox 14:54:50 INFO - mozversion application_remotingname: firefox 14:54:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:54:50 INFO - mozversion application_vendor: Mozilla 14:54:50 INFO - mozversion application_version: 50.0a1 14:54:50 INFO - mozversion platform_buildid: 20160720133419 14:54:50 INFO - mozversion platform_changeset: 36b524e69dc5250a87d8f6c42a369c817d508f39 14:54:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:54:50 INFO - mozversion platform_version: 50.0a1 14:54:50 INFO - using testdate: 1469051690 14:54:50 INFO - actual date: 1469051690 14:54:50 INFO - starting webserver on 'localhost:34045' 14:54:50 INFO - SUITE-START | Running 2 tests 14:54:50 INFO - TEST-START | dromaeo_css 14:54:50 INFO - operating with platform_type : linux_ 14:54:50 INFO - Initialising browser for dromaeo_css test... 14:54:50 INFO - TEST-INFO | started process 22292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplU6MeX/profile http://localhost:34045/getInfo.html) 14:54:54 INFO - PROCESS | 22292 | __metrics Screen width/height:1600/1200 14:54:54 INFO - PROCESS | 22292 | colorDepth:24 14:54:54 INFO - PROCESS | 22292 | Browser inner width/height: 1024/697 14:54:54 INFO - PROCESS | 22292 | __metrics 14:54:55 INFO - PROCESS | 22292 | 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" 14:54:55 INFO - PROCESS | 22292 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:54:56 INFO - TEST-INFO | 22292: exit 0 14:54:56 INFO - Browser initialized. 14:54:56 INFO - Running cycle 1/1 for dromaeo_css test... 14:54:56 INFO - TEST-INFO | started process 22377 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplU6MeX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:55:08 INFO - PROCESS | 22377 | RSS: Main: 158343168 14:55:08 INFO - PROCESS | 22377 | 14:56:00 INFO - PROCESS | 22377 | 1469051760635 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] 14:58:12 INFO - PROCESS | 22377 | Cycle 1(1): loaded http://localhost:34045/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34045/tests/dromaeo/cssquery-ext.html) 14:58:13 INFO - PROCESS | 22377 | RSS: Main: 242769920 14:58:13 INFO - PROCESS | 22377 | 15:01:16 INFO - PROCESS | 22377 | Cycle 1(1): loaded http://localhost:34045/tests/dromaeo/cssquery-ext.html (next: http://localhost:34045/tests/dromaeo/cssquery-jquery.html) 15:01:16 INFO - PROCESS | 22377 | RSS: Main: 246104064 15:01:16 INFO - PROCESS | 22377 | 15:04:18 INFO - PROCESS | 22377 | Cycle 1(1): loaded http://localhost:34045/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34045/tests/dromaeo/cssquery-mootools.html) 15:04:19 INFO - PROCESS | 22377 | RSS: Main: 191098880 15:04:19 INFO - PROCESS | 22377 | 15:07:21 INFO - PROCESS | 22377 | Cycle 1(1): loaded http://localhost:34045/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34045/tests/dromaeo/cssquery-prototype.html) 15:07:22 INFO - PROCESS | 22377 | RSS: Main: 189960192 15:07:22 INFO - PROCESS | 22377 | 15:10:25 INFO - PROCESS | 22377 | Cycle 1(1): loaded http://localhost:34045/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34045/tests/dromaeo/cssquery-yui.html) 15:10:26 INFO - PROCESS | 22377 | RSS: Main: 189685760 15:10:26 INFO - PROCESS | 22377 | 15:13:28 INFO - PROCESS | 22377 | Cycle 1(1): loaded http://localhost:34045/tests/dromaeo/cssquery-yui.html (next: http://localhost:34045/tests/dromaeo/cssquery-dojo.html) 15:13:29 INFO - PROCESS | 22377 | RSS: Main: 280674304 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | __start_tp_report 15:13:29 INFO - PROCESS | 22377 | _x_x_mozilla_page_load 15:13:29 INFO - PROCESS | 22377 | _x_x_mozilla_page_load_details 15:13:29 INFO - PROCESS | 22377 | |i|pagename|runs| 15:13:29 INFO - PROCESS | 22377 | |0;dojo.html;2594.3125071855957;2613.2290974162643;2645.9603105953406;2648.311439025853;2648.6821581410222;4608.446986361633;4725.2675835245545;4728.1962066448705;4737.431508219019;4769.974455492069;4700.48294687585;4702.435707715071;4709.5055019223;4749.216379297412;4769.022350418156;4287.164003019412;4343.370211319357;4365.170617582665;4415.094905544356;4499.932501012488;4405.229084910144;4442.577955094261;4454.844080457185;4463.821447142111;4479.17135329965;4222.831086756526;4252.043290259698;4255.638270746959;4258.5954334338185;4360.127974405116;4269.530351661315;4281.97859010703;4284.871453856358;4363.672724545671;4370.825166993316;4305.935410968838;4316.352547117926;4324.675649326313;4350.477942646871;4357.193919124972;4571.679982401233;4662.650301227422;4765.0231702500905;4766.451858036301;4774.546418090275;5037.773300201499;5059.519345662156;5060.84817455477;5102.974485127587;5106.719130447824;5089.54194122527;5110.897782044338;5138.511841375063;5150.922736158961;5165.380154381498;4592.563706447882;4674.555917187861;4758.2386818108935;4798.616110711169;4806.639502037361;5116.027954688671;5189.3253876996505;5223.843284701465;5224.529792318634;5242.97378513105;4978.875528111754;5013.072581572421;5029.874253143701;5029.949700502948;5030.647854650139;4922.655414121049;4956.430010548833;5061.240813877874;5071.771770270347;5133.614978876599;4999.900002000012;5063.5695965843315;5070.467600901852;5104.3619547556555;5105.821296254613;5026.421961474404;5031.245313203049;5034.345535080416;5039.924401133986;5087.033463641897;4312.633426158749;4317.050248945227;4320.308750599889;4328.199283132631;4361.323994780814;3659.7621154624862;3668.761530500509;3712.3132220539287;3720.1815600567834;3762.2287431076566;4383.430154079949;4392.209402307615;4418.1826362122465;4431.756253406029;4438.068005718836;5109.718965456861;5116.820911268088;5121.231815227746;5122.846314610568;5162.09663308927;4120.2583534964;4310.590493903074;4349.869503914888;4366.563343665672;4409.647228221735;4592.632589392907;4594.341128581658;4597.42532183477;4620.907581848344;4641.373414589055;4799.448063472606;4825.059113472909;4828.251620998751;4870.0746858097555;4881.389826271716;4942.283368911416;5018.172001619692;5051.469595692546;5082.567981721595;5083.262926875508;4803.183458811941;4820.39745031871;4830.082284365959;4861;4869.732164730832;4986.950130498649;4990.3762029746285;4992.301077849177;5001.299818025406;5032.798688052437;4907.33750943618;4984.028114517634;5011.098002359465;5024.497550244946;5093.898122037613;4912.606991440604;4918.43438004634;4918.483559226373;4941.308216849716;4942.777575009205;4899.951000490092;4907.141250281258;4921.064997650435;4930.038642464685;4964.602831773377;4696.671233013656;4811.422629284508;4847.903041939071;4854.150523658416;4900.681455705368;4941.851744447672;4962.131626965339;4962.404511458648;4973.52751488608;5011.949880501148;4828.830990915301;4845.030993801183;4845.515448455127;4848.369711937426;4892.584130348961 15:13:29 INFO - PROCESS | 22377 | |1;ext.html;548.4707257496515;556.8385168301195;559.3679142568895;561.3796754586182;568.2499101186436;29844.999999999975;40153;41240.58759412409;41251.793741031324;45468.54531454684;43022.56977430225;43335.34996975048;43466.56533434665;43468.782656086674;43474;7868.803279918005;7992.800179995517;8003.399745019147;8004.51972881629;8005.439619226657;7585.127710313302;10116.797664046715;10369.740756481073;10377;10698.358098514114;10388.844167337496;10487.790244195112;10487.895121048807;10525.6316028939;10539.631112911049;7586.203448637869;10418;10470.528826202763;10491.475426228697;10496.317739346918;9987.400755954666;10387.117095046855;10393.84409233862;10729.731756706067;10732.73168170794;40469.595304046874;40788.18423631541;40823.38764918499;40936;40940;2812.1985234208355;2939.2798764302884;3073.2470544716466;3515.1387909962223;3738.588755236949;28393.290167745767;28730.425391492052;28879.278018049718;29094.563581546292;30218.69781302181;3164;3383.915402114942;3396.6433524480067;3439.724822014233;3481.2863363010524;273848;275956.6202168996;277152;277460;277505;31976.840115799274;32859.1785205367;57688.71155644195;58357.416425835196;58419.70790146022;34185;34370.48444273338;34390.48414273788;34415.655843441746;34477;12542.623721288375;12591.307478088609;12602.424696912885;12635.74728505443;12670;9456.669016584387;10888.29226100301;11287.774244515063;11369.2609980351;11381.317120972768;7720.652570634335;7758;7819.139894611588;7892.763217103496;7895.526268423913;3107.984460077685;3165.4302225599613;3171.6511183769767;3190.266238765097;3219.7263232625487;916.848719961199;936.7189843047195;964.5514835601479;983.4492684096786;984.2372161574693;26831.86584067067;28143.577846332322;28331.43337133246;28421.57367639487;28827.711722882923;944.4427787605346;956.4500412262837;982.4891056650501;1047.4814966591587;1056.3820165203442;14515.637109072357;14979.176145311894;14982.550523484313;15285.541433757006;15410.152441615819;335091;337173;338109;338441;338710;48349.5165048345;48879.26681099786;48926.75536622294;48970.755146224044;49003.50996490061;1084.159776173472;1133.7675776465805;1177.664365655784;1179.6755892129734;1206.324458303353;28630;29923.55114673238;30233.69766302397;30349;30888.53667194949;43327.13345733005;43774;43787.78106109449;43809.34285985777;43857;333072;334371;335112;335191;335466;22972.885135574215;24905.750942490344;25858.61212081858;25892.61161082623;27090.593641095005;42397.152056959305;42776.57223427726;42849.14301714011;43315.133697326506;43392;39693.404598930465;40451.59548404478;40782.388264176654;41375.37936931009;42482.150356993305;36779.08052298607;37486.43770343393;38071.238575227784;38648;39076.2184756309;36816.26367472689;37089.25821483609;38097;38265.80867095647;40903.59096409117;30998;33912.3217535643;34211.82894085613;34799.65200347964;34864;25400.74599253984;25756;26013.73986260113;26066.73933260719;26261 15:13:29 INFO - PROCESS | 22377 | |2;jquery.html;362.1760494874159;367.68562878738686;370.7071413583269;379.31344266876954;381.3402813133281;16193.352265909365;17987.4603761887;18485.815141848576;18493.167807448663;18497.352592659277;16224.756628650579;16335.754963675554;16875;16946.406875759352;17827.465176044738;4732.952670473294;4954.331165292693;4962.379702537183;4963.70217786934;4964.60283177345;4742.075295317414;4843.612510999112;4873.537013983666;4909.091818013679;4921.7785199666105;4864.270359446076;4870.025994801054;4872.171730805771;4872.196087645534;4884.413870335565;4845.3216549683075;4849.102915960559;4860.586850117745;5163.664361816507;5435.10320797068;5384.138537833927;5386.622936394454;5391.81128660499;5394.32570928634;5396.5952553558245;12734.172278801849;16750.58123546909;19943.102560384817;21078.26226082095;21177.8941105295;1858.5911099558075;1866.150901339887;1866.7293242479764;2008.9252250046154;2054.907529161191;9110.635574577009;9235.261179105655;9286.628534858597;9813.754656133582;9841.606335746634;1733.904635245061;1769.4780039888265;1824.14265295311;1849.1309084730156;1857.9163937622839;61626;62904.685476572326;70817.64591177063;76591.61704191443;76836.99999999945;9915.157211637092;10234.6417875374;10412.271141020208;10416.32293900894;10460.843087353696;13076.869231307755;13098.410571524304;13098.410571524304;13123;13134.934325328311;7367.668454919542;7381.188069312371;7566.924330756622;7694.807629809299;7696.923030769621;7118.9644051779405;7166.390856777232;7196.208417074117;7343;7386.852262954711;4108.116754897711;4371;4380.7152535085115;4473.4408198975125;4492.797824097924;1811.184966764961;1837.8529717622557;1847.2057015483203;1861.088066847262;1865.3844231403605;816.5998660656236;856.3448961544424;873.6330741088758;909.8544232922701;956.0917128727737;16501.752473712902;16616.086115263533;17670.558236044202;17903.10484475771;18086.18612162456;826.557791581501;841.6044459104211;857.9184977427135;881.405051590174;922.6586163119689;9386.67146649864;9870.21038316933;9875.753106172404;9952.80094398108;10045.49772511386;433541;435415;436451;437909;438118;78049.2195078042;79312;79369.60315198502;81592;87165;911.968081117171;916.2715641065369;929.8233335666337;933.6872147830417;935.3826474526779;15393.461228856937;15421.383144674088;16622.750658739886;16653.25060372262;16923.576910577332;36691.44962825613;38143.42784858174;39154;39732.2053558933;40103.79948100241;47052;50735.746321268154;51371;52262.73868630632;54202;6844.726210951506;7050.753223637148;7111.111111111111;7481.364084052699;7694.730684426018;12441.37793110359;12504.187227830162;12526.436310366238;12591.748165036832;12762.68093297675;10135.290529662852;10431.321964072311;10626.628068017582;10988.615398461336;11240.494177762184;9965.402075875181;10318.380897146195;10398;10451.268411211142;10983.450827458755;10662.306950048222;10760.623378181726;10836.512356943798;10856.077233435246;10911.127109831354;7164.319389657974;7268.38218751412;7357.301056399633;7457.850842983002;7489.325960663567;10488.370697758159;10703.732406689895;10806.621768238074;10942.562297508011;11159.442027898735 15:13:29 INFO - PROCESS | 22377 | |3;mootools.html;1246.9002479801616;1372.9450821967118;1423.3310344138254;1484.8589384008521;1712.1781544858468;6573.276939536653;7460.776176714694;7725.188855170197;7735.419843511738;7914.960425197881;7777.105632852224;7831.843363132734;7839.960800196005;7843.647035883385;7849.646765895534;1829.423731524572;2160.708304378912;2209.712737344147;2243.1588154442084;2244.876531790751;2204.184451752854;2226;2226.0539270809923;2229.7435794883636;2237.105157936822;2151.752548456932;2191.6822060801173;2221.80003799658;2232.274510784002;2232.843700940935;1830.2038613203167;2102.6425507663735;2140.785921407862;2142.432255452291;2147.634902066645;2155.9784402155933;2203.9449013774624;2204.1844517528457;2206.172685243034;2207.5033117548583;7898.328642065431;7927.5243485391065;7935.166807485189;7946.404019698546;7970.960145199295;4601.815927362902;4736.052789442126;4741.336212930192;4754.334393184957;4755.524447555252;2806.3966247256735;2941.264683829043;2974.7768917331287;2975.1372102090368;2989.925251868699;902.2691619787993;941.8587211918199;946.2099147212017;949.4255975135083;952.7284723853669;10668.733281667943;10682.092022178123;10720.410377429238;10722.0886224671;10729.731756706067;4118.8352465901025;4262.850800221977;4336.956630433719;4345.565443455629;4368.4321038264825;9139.588718507683;9145.222656074175;9148.725538233864;9149.405288656217;9149.679761208326;4619.930701039487;4651.395318608559;4655.976720116378;4657.6273898088075;4663.696859704109;3748.493953316322;4116.115035267432;4166.66666666666;4205.747655140702;4284.378765079046;3618.7466877318334;3669.7064234861145;3818.1409182933617;3821.464994900716;3825.540935087807;1195.7907366210877;1233.0505510756668;1237.0845574275152;1246.046774217724;1250.3435696259537;683.2040672616446;697.5326531224092;716.2515171645642;784.8587254294282;836.1471299274828;5205.6616319939085;5337.452138879681;5446.509814116748;5460.754266058037;5705.087186050295;397.94826672532383;403.1252182763352;407.0779684015707;411.5246889842237;417.34268527069986;3784.0729021389934;3844.3464611016193;3853.5761066282685;3862.111714305669;3930.390789427643;9095.727128186165;9395.389299695498;9643.373180743229;9658.227341812639;9662.3719458235;9594.280428967715;9608.66369677058;9611.519424028911;9612.951935240279;9613.375130616487;7321.560706357635;7324.780256592311;7331.523450975669;7335.156457007406;7393.149787774367;5432.56539476833;5434.076207044733;5468.835934921959;5489.752961116839;5702.144678298288;8806;8823.823523529622;8914.910850891409;8918.821623567623;8982.461052336881;8874.86687699672;8962.68630597952;9131.269498440242;9173.816523669355;9198.908010920073;5942.138389933349;6478.902816457851;6861.965690171517;6977.581345119309;6984.965075174591;7402.037735094404;7617.885731714139;7626.54240745557;7630.1606823248285;7631.465797394129;6615.569987950767;6687.09724187248;6798.490113241428;6808.659567021728;7002.299770022957;6314;6685.39831415175;6746.69639866217;6846.760363387258;6867.931320686729;6133.84665383369;6343.4608058313715;6348.396902294275;6355.809325720235;6377.7767778127545;4367.45406824147;4412.117576484652;4512.458504979423;4586.220342541709;4613.19269127908;4401;4630.652701047367;4642.651801114997;4673.836415725569;4711.81152753886 15:13:29 INFO - PROCESS | 22377 | |4;prototype.html;300.2089494182828;302.30017509465586;305.6378191842665;305.73095675805286;307.8691556088663;4765.90468190636;4811.566958973692;4813.892804654932;4830.44449888263;4830.468648448667;4748.406449193851;4761.833335833247;4768.2847572864;4768.5946694531;4769.592970073828;3575.410057340536;3674.926501469969;3712.83292251848;3764.623537646241;3792.317382871082;3753.155540003491;3763.5483741951;3773.9622603774023;3782.6595606395417;3791.1659434924272;3666.523351964241;3712.721545884056;3724.9627503724887;3734.4585035169885;3738.7101449999705;3555.893323200295;3604.6029390455315;3734.1411475360546;3750.6811920986747;3778.886633401002;3300.6864347886913;3308.8676452941854;3311.1391038329966;3321.684439978198;3325.118843506473;4640.211164102111;4641.9535804641855;4644.674872758908;4655.79048942795;4677.18149323867;4166.687498437629;4273.743575385456;4308.095299987007;4308.569143085698;4314.029343397742;4909.484504127086;4917.606591472673;4925.581325587329;4929.507049295078;4934.876628084291;3602.171500554887;3604.567451905788;3614.7831130132013;3627.7460577759566;3636.7636103653176;5094.821681241138;5227.849873027928;5232.3459567554055;5233.371995360543;5237.633365664405;3713.628637136265;3717.386631205875;3727.8816355093363;3732.3654978653694;3738.6074462181623;3781.0925377909102;3789.0906182515996;3791.2038471921205;3791.848326066982;3792.829322680486;4270.103278311527;4272.188284225987;4272.46594175728;4278.251306021434;4285.357196420562;4825.30033145192;4975.686305867789;4989.760751211214;4991.800327986913;4999.325091112726;1293.838270216223;1309.4827543120414;1315.967100822468;1316.1708123881892;1319.5484966536924;816.8978877640295;860.9668397922518;862.8705694145929;874.3660845886682;876.3514998900768;4292.48490181174;4365.694401391936;4393.340998850134;4438.933415998763;4445.732966104644;5142.537171654569;5153.845384638467;5155.845324640267;5169.560587350042;5172;4385.122975404932;4389.078293558388;4401.735895846259;4415.955840441618;4420.403245561873;2585.2373549802855;2704.905328313539;2710.295323215978;2721.2788611018095;2729.3586007288272;4877.3903262092235;4887.560119589254;4897.48178064794;4900.5099490051425;4916.139675556763;5015.749212539358;5250.579953603703;5262.97368513155;5264.552513036435;5268.473152684701;4912.975435122801;4931.137051016058;4934.087193869145;4944.283078953532;4950.331705219821;5189.273501709839;5195.038917800219;5197.896042079062;5216.739163041757;5219.451957544403;4854.854354369375;5057.393112826484;5115.2071428928575;5126.2054381570915;5136.640435169502;4929.69363118781;4931.50684931504;4933.654644174873;4935.680230291757;4948.307236986897;3751.343514884939;3794.886153415402;3814.5041144652128;3830.32969230378;3838.673712734449;4996.375453068366;5177.870553236199;5185.818496352609;5186.507281808223;5212.296339994053;4976.377952755905;4980.153373926513;4985.177445721415;4987.950120498748;5012.924806127838;4992.452339774536;4995.900081998267;5016.165866285537;5025.597952163891;5033.924491132562;4761.262004389325;4900.215965445512;4947.2579113133315;4959.181735013683;4961.479044700254;4767.928481072718;4859.878503037452;4964.4539100698175;4994.1759609663995;5033.842216290201;4951.158303088411;4952.702837829742;4954.752262386938;4967.105920934267;4967.577755890789 15:13:29 INFO - PROCESS | 22377 | |5;yui.html;621.3972446726675;649.4512137244027;657.62186742623;701.7578935267334;734.9154847192572;13276.800847987275;14040.508582199638;14068.22624755638;14077.929610351946;14082.225477598753;13374.866251337484;13505.459781608724;13533.255670938095;13534.796978045313;13581.456741730319;614.0850133301371;628.6196850905205;749.2769477454256;767.6392095715005;779.3258831111076;761.6344154805697;771.6026246483037;771.7993321736361;772.112071118213;772.428402981795;825.405707890321;826.6652005937605;826.7106512720529;826.8222332198576;827.4993628854546;639.2967735490922;768.2739810878721;804.6620419423856;822.2928281677753;822.9259366657;590.8700085981077;591.8816236752667;592.7569696424489;593.6111846740392;594.5659668442019;13719.1082579633;13766.449342026306;13781.173129612154;13794.793078103836;13802.102863313852;11892.940535297355;11963.46164422603;11964.102692298113;12253.754924901541;12996.155249908727;11347.829782553268;11373.601923932705;11566.595169169039;11586.246893951951;12045;3920.7843568603716;3986.920261594752;4015.19696060789;4015.4779878615886;4031.07285324377;64566.67716661434;66071.66964165197;66362.66818665924;66426.66786666083;66755.3324466754;9704.514774261259;9820.361676491148;9959.203263738884;10120.038596333337;10242.129418999319;16471.423500177436;17044.14779261032;19111.426657200307;19140.425787226406;19253.422397328104;6772.5936443813525;6915.619640919697;6957.443404527626;7014.473914456436;7153.391961683211;3688.66801987817;3825.407061905409;3835.4438606401914;3850.9037274068373;3900.8244628991765;2978.4936560784718;3093.752499800011;3118.9064328070194;3119.298157914451;3128.296133370019;529.4282175250797;532.0476347338298;533.757140501071;537.3793268774572;543.6765124750767;407.7614595461629;490.225443798797;507.50264740553723;539.398570593785;552.4668694709625;7758.262965018314;7870.72452464161;7971.521708697497;8348.123447037973;8551.871721924175;555.1201345866766;567.897778399892;581.520245797219;584.3951510186936;587.1193210184723;4033.576474470197;4057.5536690963972;4317.870463886088;4327.740335579875;4359.542248063971;92781.5360923191;95627.56558651626;99971.50014249881;102279.488602558;102576.48711756543;83783.5810820942;83968.58015709883;85767.5711621438;85949.57025214958;86501;73062.63468682622;75021.97763992418;75962.24037759552;76747.61626191833;77503;8019.992502811446;8035.839283214186;8124.350051995708;8185.508869467851;8515.1059138792;44766.77616611896;45796.7710161447;46957.2956405661;47407.28889066598;47500.52499474961;59710.40289597048;66399;67195;67862.32137678757;68020.6598967002;9584.520773961134;9862.358946668444;9897.911229764864;9912.801743964936;10190.541425635716;11014.834777478183;11292.887071129184;11293.943530282297;11371.772564548828;11392.772144556897;10136.421082077366;10537.26239163282;10695.942986821748;10803.297785643908;11051.171162163031;10639.680809575726;10702.625408110678;10721.195910306602;10743.623973161215;11161.44192790341;10215.846762298423;10489.318194317346;11601.187916846078;11673.182877198515;11919.642410727693;10392.272540922366;10420.166386688896;10648.467576620984;10780.17436904643;10808.459577021275;10205.74485637865;10758.462076895967;10804.891951080388;10929.945350273198;11063.391513466837 15:13:29 INFO - PROCESS | 22377 | __end_tp_report 15:13:29 INFO - PROCESS | 22377 | __start_cc_report 15:13:29 INFO - PROCESS | 22377 | _x_x_mozilla_cycle_collect,921 15:13:29 INFO - PROCESS | 22377 | __end_cc_report 15:13:29 INFO - PROCESS | 22377 | __startTimestamp1469052809133__endTimestamp 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | ------- Summary: start ------- 15:13:29 INFO - PROCESS | 22377 | Number of tests: 6 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | [#0] dojo.html Cycles:165 Average:4682.40 Median:4828.83 stddev:497.39 (10.3%) stddev-sans-first:471.17 15:13:29 INFO - PROCESS | 22377 | Values: 2594.3 2613.2 2646.0 2648.3 2648.7 4608.4 4725.3 4728.2 4737.4 4770.0 4700.5 4702.4 4709.5 4749.2 4769.0 4287.2 4343.4 4365.2 4415.1 4499.9 4405.2 4442.6 4454.8 4463.8 4479.2 4222.8 4252.0 4255.6 4258.6 4360.1 4269.5 4282.0 4284.9 4363.7 4370.8 4305.9 4316.4 4324.7 4350.5 4357.2 4571.7 4662.7 4765.0 4766.5 4774.5 5037.8 5059.5 5060.8 5103.0 5106.7 5089.5 5110.9 5138.5 5150.9 5165.4 4592.6 4674.6 4758.2 4798.6 4806.6 5116.0 5189.3 5223.8 5224.5 5243.0 4978.9 5013.1 5029.9 5029.9 5030.6 4922.7 4956.4 5061.2 5071.8 5133.6 4999.9 5063.6 5070.5 5104.4 5105.8 5026.4 5031.2 5034.3 5039.9 5087.0 4312.6 4317.1 4320.3 4328.2 4361.3 3659.8 3668.8 3712.3 3720.2 3762.2 4383.4 4392.2 4418.2 4431.8 4438.1 5109.7 5116.8 5121.2 5122.8 5162.1 4120.3 4310.6 4349.9 4366.6 4409.6 4592.6 4594.3 4597.4 4620.9 4641.4 4799.4 4825.1 4828.3 4870.1 4881.4 4942.3 5018.2 5051.5 5082.6 5083.3 4803.2 4820.4 4830.1 4861.0 4869.7 4987.0 4990.4 4992.3 5001.3 5032.8 4907.3 4984.0 5011.1 5024.5 5093.9 4912.6 4918.4 4918.5 4941.3 4942.8 4900.0 4907.1 4921.1 4930.0 4964.6 4696.7 4811.4 4847.9 4854.2 4900.7 4941.9 4962.1 4962.4 4973.5 5011.9 4828.8 4845.0 4845.5 4848.4 4892.6 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | [#1] ext.html Cycles:180 Average:46882.19 Median:338575.50 stddev:83353.54 (24.6%) stddev-sans-first:83514.77 15:13:29 INFO - PROCESS | 22377 | Values: 548.5 556.8 559.4 561.4 568.2 29845.0 40153.0 41240.6 41251.8 45468.5 43022.6 43335.3 43466.6 43468.8 43474.0 7868.8 7992.8 8003.4 8004.5 8005.4 7585.1 10116.8 10369.7 10377.0 10698.4 10388.8 10487.8 10487.9 10525.6 10539.6 7586.2 10418.0 10470.5 10491.5 10496.3 9987.4 10387.1 10393.8 10729.7 10732.7 40469.6 40788.2 40823.4 40936.0 40940.0 2812.2 2939.3 3073.2 3515.1 3738.6 28393.3 28730.4 28879.3 29094.6 30218.7 3164.0 3383.9 3396.6 3439.7 3481.3 273848.0 275956.6 277152.0 277460.0 277505.0 31976.8 32859.2 57688.7 58357.4 58419.7 34185.0 34370.5 34390.5 34415.7 34477.0 12542.6 12591.3 12602.4 12635.7 12670.0 9456.7 10888.3 11287.8 11369.3 11381.3 7720.7 7758.0 7819.1 7892.8 7895.5 3108.0 3165.4 3171.7 3190.3 3219.7 916.8 936.7 964.6 983.4 984.2 26831.9 28143.6 28331.4 28421.6 28827.7 944.4 956.5 982.5 1047.5 1056.4 14515.6 14979.2 14982.6 15285.5 15410.2 335091.0 337173.0 338109.0 338441.0 338710.0 48349.5 48879.3 48926.8 48970.8 49003.5 1084.2 1133.8 1177.7 1179.7 1206.3 28630.0 29923.6 30233.7 30349.0 30888.5 43327.1 43774.0 43787.8 43809.3 43857.0 333072.0 334371.0 335112.0 335191.0 335466.0 22972.9 24905.8 25858.6 25892.6 27090.6 42397.2 42776.6 42849.1 43315.1 43392.0 39693.4 40451.6 40782.4 41375.4 42482.2 36779.1 37486.4 38071.2 38648.0 39076.2 36816.3 37089.3 38097.0 38265.8 40903.6 30998.0 33912.3 34211.8 34799.7 34864.0 25400.7 25756.0 26013.7 26066.7 26261.0 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | [#2] jquery.html Cycles:180 Average:25808.44 Median:221249.36 stddev:71878.07 (32.5%) stddev-sans-first:72054.31 15:13:29 INFO - PROCESS | 22377 | Values: 362.2 367.7 370.7 379.3 381.3 16193.4 17987.5 18485.8 18493.2 18497.4 16224.8 16335.8 16875.0 16946.4 17827.5 4733.0 4954.3 4962.4 4963.7 4964.6 4742.1 4843.6 4873.5 4909.1 4921.8 4864.3 4870.0 4872.2 4872.2 4884.4 4845.3 4849.1 4860.6 5163.7 5435.1 5384.1 5386.6 5391.8 5394.3 5396.6 12734.2 16750.6 19943.1 21078.3 21177.9 1858.6 1866.2 1866.7 2008.9 2054.9 9110.6 9235.3 9286.6 9813.8 9841.6 1733.9 1769.5 1824.1 1849.1 1857.9 61626.0 62904.7 70817.6 76591.6 76837.0 9915.2 10234.6 10412.3 10416.3 10460.8 13076.9 13098.4 13098.4 13123.0 13134.9 7367.7 7381.2 7566.9 7694.8 7696.9 7119.0 7166.4 7196.2 7343.0 7386.9 4108.1 4371.0 4380.7 4473.4 4492.8 1811.2 1837.9 1847.2 1861.1 1865.4 816.6 856.3 873.6 909.9 956.1 16501.8 16616.1 17670.6 17903.1 18086.2 826.6 841.6 857.9 881.4 922.7 9386.7 9870.2 9875.8 9952.8 10045.5 433541.0 435415.0 436451.0 437909.0 438118.0 78049.2 79312.0 79369.6 81592.0 87165.0 912.0 916.3 929.8 933.7 935.4 15393.5 15421.4 16622.8 16653.3 16923.6 36691.4 38143.4 39154.0 39732.2 40103.8 47052.0 50735.7 51371.0 52262.7 54202.0 6844.7 7050.8 7111.1 7481.4 7694.7 12441.4 12504.2 12526.4 12591.7 12762.7 10135.3 10431.3 10626.6 10988.6 11240.5 9965.4 10318.4 10398.0 10451.3 10983.5 10662.3 10760.6 10836.5 10856.1 10911.1 7164.3 7268.4 7357.3 7457.9 7489.3 10488.4 10703.7 10806.6 10942.6 11159.4 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | [#3] mootools.html Cycles:180 Average:5151.87 Median:5271.56 stddev:2896.00 (54.9%) stddev-sans-first:2889.25 15:13:29 INFO - PROCESS | 22377 | Values: 1246.9 1372.9 1423.3 1484.9 1712.2 6573.3 7460.8 7725.2 7735.4 7915.0 7777.1 7831.8 7840.0 7843.6 7849.6 1829.4 2160.7 2209.7 2243.2 2244.9 2204.2 2226.0 2226.1 2229.7 2237.1 2151.8 2191.7 2221.8 2232.3 2232.8 1830.2 2102.6 2140.8 2142.4 2147.6 2156.0 2203.9 2204.2 2206.2 2207.5 7898.3 7927.5 7935.2 7946.4 7971.0 4601.8 4736.1 4741.3 4754.3 4755.5 2806.4 2941.3 2974.8 2975.1 2989.9 902.3 941.9 946.2 949.4 952.7 10668.7 10682.1 10720.4 10722.1 10729.7 4118.8 4262.9 4337.0 4345.6 4368.4 9139.6 9145.2 9148.7 9149.4 9149.7 4619.9 4651.4 4656.0 4657.6 4663.7 3748.5 4116.1 4166.7 4205.7 4284.4 3618.7 3669.7 3818.1 3821.5 3825.5 1195.8 1233.1 1237.1 1246.0 1250.3 683.2 697.5 716.3 784.9 836.1 5205.7 5337.5 5446.5 5460.8 5705.1 397.9 403.1 407.1 411.5 417.3 3784.1 3844.3 3853.6 3862.1 3930.4 9095.7 9395.4 9643.4 9658.2 9662.4 9594.3 9608.7 9611.5 9613.0 9613.4 7321.6 7324.8 7331.5 7335.2 7393.1 5432.6 5434.1 5468.8 5489.8 5702.1 8806.0 8823.8 8914.9 8918.8 8982.5 8874.9 8962.7 9131.3 9173.8 9198.9 5942.1 6478.9 6862.0 6977.6 6985.0 7402.0 7617.9 7626.5 7630.2 7631.5 6615.6 6687.1 6798.5 6808.7 7002.3 6314.0 6685.4 6746.7 6846.8 6867.9 6133.8 6343.5 6348.4 6355.8 6377.8 4367.5 4412.1 4512.5 4586.2 4613.2 4401.0 4630.7 4642.7 4673.8 4711.8 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | [#4] prototype.html Cycles:180 Average:4174.56 Median:4768.44 stddev:1202.33 (25.2%) stddev-sans-first:1170.01 15:13:29 INFO - PROCESS | 22377 | Values: 300.2 302.3 305.6 305.7 307.9 4765.9 4811.6 4813.9 4830.4 4830.5 4748.4 4761.8 4768.3 4768.6 4769.6 3575.4 3674.9 3712.8 3764.6 3792.3 3753.2 3763.5 3774.0 3782.7 3791.2 3666.5 3712.7 3725.0 3734.5 3738.7 3555.9 3604.6 3734.1 3750.7 3778.9 3300.7 3308.9 3311.1 3321.7 3325.1 4640.2 4642.0 4644.7 4655.8 4677.2 4166.7 4273.7 4308.1 4308.6 4314.0 4909.5 4917.6 4925.6 4929.5 4934.9 3602.2 3604.6 3614.8 3627.7 3636.8 5094.8 5227.8 5232.3 5233.4 5237.6 3713.6 3717.4 3727.9 3732.4 3738.6 3781.1 3789.1 3791.2 3791.8 3792.8 4270.1 4272.2 4272.5 4278.3 4285.4 4825.3 4975.7 4989.8 4991.8 4999.3 1293.8 1309.5 1316.0 1316.2 1319.5 816.9 861.0 862.9 874.4 876.4 4292.5 4365.7 4393.3 4438.9 4445.7 5142.5 5153.8 5155.8 5169.6 5172.0 4385.1 4389.1 4401.7 4416.0 4420.4 2585.2 2704.9 2710.3 2721.3 2729.4 4877.4 4887.6 4897.5 4900.5 4916.1 5015.7 5250.6 5263.0 5264.6 5268.5 4913.0 4931.1 4934.1 4944.3 4950.3 5189.3 5195.0 5197.9 5216.7 5219.5 4854.9 5057.4 5115.2 5126.2 5136.6 4929.7 4931.5 4933.7 4935.7 4948.3 3751.3 3794.9 3814.5 3830.3 3838.7 4996.4 5177.9 5185.8 5186.5 5212.3 4976.4 4980.2 4985.2 4988.0 5012.9 4992.5 4995.9 5016.2 5025.6 5033.9 4761.3 4900.2 4947.3 4959.2 4961.5 4767.9 4859.9 4964.5 4994.2 5033.8 4951.2 4952.7 4954.8 4967.1 4967.6 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - PROCESS | 22377 | [#5] yui.html Cycles:180 Average:18068.75 Median:23995.38 stddev:25988.68 (108.3%) stddev-sans-first:26028.56 15:13:29 INFO - PROCESS | 22377 | Values: 621.4 649.5 657.6 701.8 734.9 13276.8 14040.5 14068.2 14077.9 14082.2 13374.9 13505.5 13533.3 13534.8 13581.5 614.1 628.6 749.3 767.6 779.3 761.6 771.6 771.8 772.1 772.4 825.4 826.7 826.7 826.8 827.5 639.3 768.3 804.7 822.3 822.9 590.9 591.9 592.8 593.6 594.6 13719.1 13766.4 13781.2 13794.8 13802.1 11892.9 11963.5 11964.1 12253.8 12996.2 11347.8 11373.6 11566.6 11586.2 12045.0 3920.8 3986.9 4015.2 4015.5 4031.1 64566.7 66071.7 66362.7 66426.7 66755.3 9704.5 9820.4 9959.2 10120.0 10242.1 16471.4 17044.1 19111.4 19140.4 19253.4 6772.6 6915.6 6957.4 7014.5 7153.4 3688.7 3825.4 3835.4 3850.9 3900.8 2978.5 3093.8 3118.9 3119.3 3128.3 529.4 532.0 533.8 537.4 543.7 407.8 490.2 507.5 539.4 552.5 7758.3 7870.7 7971.5 8348.1 8551.9 555.1 567.9 581.5 584.4 587.1 4033.6 4057.6 4317.9 4327.7 4359.5 92781.5 95627.6 99971.5 102279.5 102576.5 83783.6 83968.6 85767.6 85949.6 86501.0 73062.6 75022.0 75962.2 76747.6 77503.0 8020.0 8035.8 8124.4 8185.5 8515.1 44766.8 45796.8 46957.3 47407.3 47500.5 59710.4 66399.0 67195.0 67862.3 68020.7 9584.5 9862.4 9897.9 9912.8 10190.5 11014.8 11292.9 11293.9 11371.8 11392.8 10136.4 10537.3 10695.9 10803.3 11051.2 10639.7 10702.6 10721.2 10743.6 11161.4 10215.8 10489.3 11601.2 11673.2 11919.6 10392.3 10420.2 10648.5 10780.2 10808.5 10205.7 10758.5 10804.9 10929.9 11063.4 15:13:29 INFO - PROCESS | 22377 | -------- Summary: end -------- 15:13:29 INFO - PROCESS | 22377 | 15:13:29 INFO - TEST-INFO | 22377: exit 0 15:13:29 INFO - TEST-OK | dromaeo_css | took 1119835ms 15:13:29 INFO - TEST-START | kraken 15:13:29 INFO - operating with platform_type : linux_ 15:13:29 INFO - Initialising browser for kraken test... 15:13:29 INFO - TEST-INFO | started process 22563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpteTylm/profile http://localhost:34045/getInfo.html) 15:13:34 INFO - PROCESS | 22563 | __metrics Screen width/height:1600/1200 15:13:34 INFO - PROCESS | 22563 | colorDepth:24 15:13:34 INFO - PROCESS | 22563 | Browser inner width/height: 1024/697 15:13:34 INFO - PROCESS | 22563 | __metrics 15:13:35 INFO - PROCESS | 22563 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:13:35 INFO - PROCESS | 22563 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:13:35 INFO - PROCESS | 22563 | console.error: PushService: 15:13:35 INFO - PROCESS | 22563 | stateChangeProcessEnqueue: Error transitioning state 15:13:35 INFO - PROCESS | 22563 | UnknownError 15:13:36 INFO - TEST-INFO | 22563: exit 0 15:13:36 INFO - Browser initialized. 15:13:36 INFO - Running cycle 1/1 for kraken test... 15:13:36 INFO - TEST-INFO | started process 22643 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpteTylm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:13:48 INFO - PROCESS | 22643 | RSS: Main: 158789632 15:13:48 INFO - PROCESS | 22643 | 15:13:51 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34045/tests/kraken/driver.html?testName=audio-beat-detection) 15:13:52 INFO - PROCESS | 22643 | RSS: Main: 214933504 15:13:52 INFO - PROCESS | 22643 | 15:13:54 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34045/tests/kraken/driver.html?testName=audio-dft) 15:13:55 INFO - PROCESS | 22643 | RSS: Main: 193581056 15:13:55 INFO - PROCESS | 22643 | 15:13:58 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34045/tests/kraken/driver.html?testName=audio-fft) 15:13:59 INFO - PROCESS | 22643 | RSS: Main: 271945728 15:13:59 INFO - PROCESS | 22643 | 15:14:01 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34045/tests/kraken/driver.html?testName=audio-oscillator) 15:14:01 INFO - PROCESS | 22643 | RSS: Main: 181338112 15:14:01 INFO - PROCESS | 22643 | 15:14:04 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34045/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:14:04 INFO - PROCESS | 22643 | RSS: Main: 186322944 15:14:04 INFO - PROCESS | 22643 | 15:14:07 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34045/tests/kraken/driver.html?testName=imaging-darkroom) 15:14:08 INFO - PROCESS | 22643 | RSS: Main: 222048256 15:14:08 INFO - PROCESS | 22643 | 15:14:12 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34045/tests/kraken/driver.html?testName=imaging-desaturate) 15:14:12 INFO - PROCESS | 22643 | RSS: Main: 216268800 15:14:12 INFO - PROCESS | 22643 | 15:14:15 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34045/tests/kraken/driver.html?testName=json-parse-financial) 15:14:16 INFO - PROCESS | 22643 | RSS: Main: 219877376 15:14:16 INFO - PROCESS | 22643 | 15:14:17 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34045/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:14:18 INFO - PROCESS | 22643 | RSS: Main: 184127488 15:14:18 INFO - PROCESS | 22643 | 15:14:20 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:14:21 INFO - PROCESS | 22643 | RSS: Main: 190513152 15:14:21 INFO - PROCESS | 22643 | 15:14:23 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:14:23 INFO - PROCESS | 22643 | RSS: Main: 194732032 15:14:23 INFO - PROCESS | 22643 | 15:14:26 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:14:27 INFO - PROCESS | 22643 | RSS: Main: 190099456 15:14:27 INFO - PROCESS | 22643 | 15:14:30 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:14:30 INFO - PROCESS | 22643 | RSS: Main: 193339392 15:14:30 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | Cycle 1(1): loaded http://localhost:34045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34045/tests/kraken/driver.html?testName=ai-astar) 15:14:32 INFO - PROCESS | 22643 | RSS: Main: 244547584 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | __start_tp_report 15:14:32 INFO - PROCESS | 22643 | _x_x_mozilla_page_load 15:14:32 INFO - PROCESS | 22643 | _x_x_mozilla_page_load_details 15:14:32 INFO - PROCESS | 22643 | |i|pagename|runs| 15:14:32 INFO - PROCESS | 22643 | |0;ai-astar;103;114;117;113;113;113;113;113;113;113 15:14:32 INFO - PROCESS | 22643 | |1;audio-beat-detection;149;125;124;176;122;123;141;122;165;123 15:14:32 INFO - PROCESS | 22643 | |2;audio-dft;162;162;160;161;164;162;164;168;161;160 15:14:32 INFO - PROCESS | 22643 | |3;audio-fft;75;76;77;109;77;76;75;75;102;76 15:14:32 INFO - PROCESS | 22643 | |4;audio-oscillator;86;86;123;85;84;84;113;84;84;122 15:14:32 INFO - PROCESS | 22643 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;116 15:14:32 INFO - PROCESS | 22643 | |6;imaging-darkroom;146;147;147;147;146;146;147;145;146;146 15:14:32 INFO - PROCESS | 22643 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 15:14:32 INFO - PROCESS | 22643 | |8;json-parse-financial;65;64;66;65;65;64;72;65;64;66 15:14:32 INFO - PROCESS | 22643 | |9;json-stringify-tinderbox;65;65;65;65;65;65;65;65;66;65 15:14:32 INFO - PROCESS | 22643 | |10;stanford-crypto-aes;80;82;88;79;79;80;78;79;84;84 15:14:32 INFO - PROCESS | 22643 | |11;stanford-crypto-ccm;140;130;131;129;127;126;127;130;176;128 15:14:32 INFO - PROCESS | 22643 | |12;stanford-crypto-pbkdf2;167;164;168;167;161;164;169;166;162;165 15:14:32 INFO - PROCESS | 22643 | |13;stanford-crypto-sha256-iterative;67;67;68;68;66;66;66;67;65;65 15:14:32 INFO - PROCESS | 22643 | __end_tp_report 15:14:32 INFO - PROCESS | 22643 | __start_cc_report 15:14:32 INFO - PROCESS | 22643 | _x_x_mozilla_cycle_collect,1934 15:14:32 INFO - PROCESS | 22643 | __end_cc_report 15:14:32 INFO - PROCESS | 22643 | __startTimestamp1469052872715__endTimestamp 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | ------- Summary: start ------- 15:14:32 INFO - PROCESS | 22643 | Number of tests: 14 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:3.57 (3.2%) stddev-sans-first:1.33 15:14:32 INFO - PROCESS | 22643 | Values: 103.0 114.0 117.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:133.00 stddev:20.06 (15.1%) stddev-sans-first:20.80 15:14:32 INFO - PROCESS | 22643 | Values: 149.0 125.0 124.0 176.0 122.0 123.0 141.0 122.0 165.0 123.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 15:14:32 INFO - PROCESS | 22643 | Values: 162.0 162.0 160.0 161.0 164.0 162.0 164.0 168.0 161.0 160.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:12.62 (16.6%) stddev-sans-first:13.14 15:14:32 INFO - PROCESS | 22643 | Values: 75.0 76.0 77.0 109.0 77.0 76.0 75.0 75.0 102.0 76.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.00 stddev:16.94 (20.2%) stddev-sans-first:17.65 15:14:32 INFO - PROCESS | 22643 | Values: 86.0 86.0 123.0 85.0 84.0 84.0 113.0 84.0 84.0 122.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 15:14:32 INFO - PROCESS | 22643 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 15:14:32 INFO - PROCESS | 22643 | Values: 146.0 147.0 147.0 147.0 146.0 146.0 147.0 145.0 146.0 146.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 15:14:32 INFO - PROCESS | 22643 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:65.00 stddev:2.37 (3.6%) stddev-sans-first:2.50 15:14:32 INFO - PROCESS | 22643 | Values: 65.0 64.0 66.0 65.0 65.0 64.0 72.0 65.0 64.0 66.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 15:14:32 INFO - PROCESS | 22643 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 66.0 65.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.00 stddev:3.16 (3.9%) stddev-sans-first:3.32 15:14:32 INFO - PROCESS | 22643 | Values: 80.0 82.0 88.0 79.0 79.0 80.0 78.0 79.0 84.0 84.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#11] stanford-crypto-ccm Cycles:10 Average:134.40 Median:130.00 stddev:15.14 (11.6%) stddev-sans-first:15.92 15:14:32 INFO - PROCESS | 22643 | Values: 140.0 130.0 131.0 129.0 127.0 126.0 127.0 130.0 176.0 128.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:166.50 stddev:2.58 (1.6%) stddev-sans-first:2.67 15:14:32 INFO - PROCESS | 22643 | Values: 167.0 164.0 168.0 167.0 161.0 164.0 169.0 166.0 162.0 165.0 15:14:32 INFO - PROCESS | 22643 | 15:14:32 INFO - PROCESS | 22643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 15:14:32 INFO - PROCESS | 22643 | Values: 67.0 67.0 68.0 68.0 66.0 66.0 66.0 67.0 65.0 65.0 15:14:32 INFO - PROCESS | 22643 | -------- Summary: end -------- 15:14:32 INFO - PROCESS | 22643 | 15:14:33 INFO - TEST-INFO | 22643: exit 0 15:14:33 INFO - TEST-OK | kraken | took 63863ms 15:14:33 INFO - SUITE-END | took 1183s 15:14:33 INFO - Completed test suite (00:19:43) 15:14:33 INFO - javascript benchmark 15:14:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2594.3125071855957, 2613.2290974162643, 2645.9603105953406, 2648.311439025853, 2648.6821581410222, 4608.446986361633, 4725.2675835245545, 4728.1962066448705, 4737.431508219019, 4769.974455492069, 4700.48294687585, 4702.435707715071, 4709.5055019223, 4749.216379297412, 4769.022350418156, 4287.164003019412, 4343.370211319357, 4365.170617582665, 4415.094905544356, 4499.932501012488, 4405.229084910144, 4442.577955094261, 4454.844080457185, 4463.821447142111, 4479.17135329965, 4222.831086756526, 4252.043290259698, 4255.638270746959, 4258.5954334338185, 4360.127974405116, 4269.530351661315, 4281.97859010703, 4284.871453856358, 4363.672724545671, 4370.825166993316, 4305.935410968838, 4316.352547117926, 4324.675649326313, 4350.477942646871, 4357.193919124972, 4571.679982401233, 4662.650301227422, 4765.0231702500905, 4766.451858036301, 4774.546418090275, 5037.773300201499, 5059.519345662156, 5060.84817455477, 5102.974485127587, 5106.719130447824, 5089.54194122527, 5110.897782044338, 5138.511841375063, 5150.922736158961, 5165.380154381498, 4592.563706447882, 4674.555917187861, 4758.2386818108935, 4798.616110711169, 4806.639502037361, 5116.027954688671, 5189.3253876996505, 5223.843284701465, 5224.529792318634, 5242.97378513105, 4978.875528111754, 5013.072581572421, 5029.874253143701, 5029.949700502948, 5030.647854650139, 4922.655414121049, 4956.430010548833, 5061.240813877874, 5071.771770270347, 5133.614978876599, 4999.900002000012, 5063.5695965843315, 5070.467600901852, 5104.3619547556555, 5105.821296254613, 5026.421961474404, 5031.245313203049, 5034.345535080416, 5039.924401133986, 5087.033463641897, 4312.633426158749, 4317.050248945227, 4320.308750599889, 4328.199283132631, 4361.323994780814, 3659.7621154624862, 3668.761530500509, 3712.3132220539287, 3720.1815600567834, 3762.2287431076566, 4383.430154079949, 4392.209402307615, 4418.1826362122465, 4431.756253406029, 4438.068005718836, 5109.718965456861, 5116.820911268088, 5121.231815227746, 5122.846314610568, 5162.09663308927, 4120.2583534964, 4310.590493903074, 4349.869503914888, 4366.563343665672, 4409.647228221735, 4592.632589392907, 4594.341128581658, 4597.42532183477, 4620.907581848344, 4641.373414589055, 4799.448063472606, 4825.059113472909, 4828.251620998751, 4870.0746858097555, 4881.389826271716, 4942.283368911416, 5018.172001619692, 5051.469595692546, 5082.567981721595, 5083.262926875508, 4803.183458811941, 4820.39745031871, 4830.082284365959, 4861.0, 4869.732164730832, 4986.950130498649, 4990.3762029746285, 4992.301077849177, 5001.299818025406, 5032.798688052437, 4907.33750943618, 4984.028114517634, 5011.098002359465, 5024.497550244946, 5093.898122037613, 4912.606991440604, 4918.43438004634, 4918.483559226373, 4941.308216849716, 4942.777575009205, 4899.951000490092, 4907.141250281258, 4921.064997650435, 4930.038642464685, 4964.602831773377, 4696.671233013656, 4811.422629284508, 4847.903041939071, 4854.150523658416, 4900.681455705368, 4941.851744447672, 4962.131626965339, 4962.404511458648, 4973.52751488608, 5011.949880501148, 4828.830990915301, 4845.030993801183, 4845.515448455127, 4848.369711937426, 4892.584130348961], "value": 4650.115887989737, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.4707257496515, 556.8385168301195, 559.3679142568895, 561.3796754586182, 568.2499101186436, 29844.999999999975, 40153.0, 41240.58759412409, 41251.793741031324, 45468.54531454684, 43022.56977430225, 43335.34996975048, 43466.56533434665, 43468.782656086674, 43474.0, 7868.803279918005, 7992.800179995517, 8003.399745019147, 8004.51972881629, 8005.439619226657, 7585.127710313302, 10116.797664046715, 10369.740756481073, 10377.0, 10698.358098514114, 10388.844167337496, 10487.790244195112, 10487.895121048807, 10525.6316028939, 10539.631112911049, 7586.203448637869, 10418.0, 10470.528826202763, 10491.475426228697, 10496.317739346918, 9987.400755954666, 10387.117095046855, 10393.84409233862, 10729.731756706067, 10732.73168170794, 40469.595304046874, 40788.18423631541, 40823.38764918499, 40936.0, 40940.0, 2812.1985234208355, 2939.2798764302884, 3073.2470544716466, 3515.1387909962223, 3738.588755236949, 28393.290167745767, 28730.425391492052, 28879.278018049718, 29094.563581546292, 30218.69781302181, 3164.0, 3383.915402114942, 3396.6433524480067, 3439.724822014233, 3481.2863363010524, 273848.0, 275956.6202168996, 277152.0, 277460.0, 277505.0, 31976.840115799274, 32859.1785205367, 57688.71155644195, 58357.416425835196, 58419.70790146022, 34185.0, 34370.48444273338, 34390.48414273788, 34415.655843441746, 34477.0, 12542.623721288375, 12591.307478088609, 12602.424696912885, 12635.74728505443, 12670.0, 9456.669016584387, 10888.29226100301, 11287.774244515063, 11369.2609980351, 11381.317120972768, 7720.652570634335, 7758.0, 7819.139894611588, 7892.763217103496, 7895.526268423913, 3107.984460077685, 3165.4302225599613, 3171.6511183769767, 3190.266238765097, 3219.7263232625487, 916.848719961199, 936.7189843047195, 964.5514835601479, 983.4492684096786, 984.2372161574693, 26831.86584067067, 28143.577846332322, 28331.43337133246, 28421.57367639487, 28827.711722882923, 944.4427787605346, 956.4500412262837, 982.4891056650501, 1047.4814966591587, 1056.3820165203442, 14515.637109072357, 14979.176145311894, 14982.550523484313, 15285.541433757006, 15410.152441615819, 335091.0, 337173.0, 338109.0, 338441.0, 338710.0, 48349.5165048345, 48879.26681099786, 48926.75536622294, 48970.755146224044, 49003.50996490061, 1084.159776173472, 1133.7675776465805, 1177.664365655784, 1179.6755892129734, 1206.324458303353, 28630.0, 29923.55114673238, 30233.69766302397, 30349.0, 30888.53667194949, 43327.13345733005, 43774.0, 43787.78106109449, 43809.34285985777, 43857.0, 333072.0, 334371.0, 335112.0, 335191.0, 335466.0, 22972.885135574215, 24905.750942490344, 25858.61212081858, 25892.61161082623, 27090.593641095005, 42397.152056959305, 42776.57223427726, 42849.14301714011, 43315.133697326506, 43392.0, 39693.404598930465, 40451.59548404478, 40782.388264176654, 41375.37936931009, 42482.150356993305, 36779.08052298607, 37486.43770343393, 38071.238575227784, 38648.0, 39076.2184756309, 36816.26367472689, 37089.25821483609, 38097.0, 38265.80867095647, 40903.59096409117, 30998.0, 33912.3217535643, 34211.82894085613, 34799.65200347964, 34864.0, 25400.74599253984, 25756.0, 26013.73986260113, 26066.73933260719, 26261.0], "value": 17215.207022011116, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.1760494874159, 367.68562878738686, 370.7071413583269, 379.31344266876954, 381.3402813133281, 16193.352265909365, 17987.4603761887, 18485.815141848576, 18493.167807448663, 18497.352592659277, 16224.756628650579, 16335.754963675554, 16875.0, 16946.406875759352, 17827.465176044738, 4732.952670473294, 4954.331165292693, 4962.379702537183, 4963.70217786934, 4964.60283177345, 4742.075295317414, 4843.612510999112, 4873.537013983666, 4909.091818013679, 4921.7785199666105, 4864.270359446076, 4870.025994801054, 4872.171730805771, 4872.196087645534, 4884.413870335565, 4845.3216549683075, 4849.102915960559, 4860.586850117745, 5163.664361816507, 5435.10320797068, 5384.138537833927, 5386.622936394454, 5391.81128660499, 5394.32570928634, 5396.5952553558245, 12734.172278801849, 16750.58123546909, 19943.102560384817, 21078.26226082095, 21177.8941105295, 1858.5911099558075, 1866.150901339887, 1866.7293242479764, 2008.9252250046154, 2054.907529161191, 9110.635574577009, 9235.261179105655, 9286.628534858597, 9813.754656133582, 9841.606335746634, 1733.904635245061, 1769.4780039888265, 1824.14265295311, 1849.1309084730156, 1857.9163937622839, 61626.0, 62904.685476572326, 70817.64591177063, 76591.61704191443, 76836.99999999945, 9915.157211637092, 10234.6417875374, 10412.271141020208, 10416.32293900894, 10460.843087353696, 13076.869231307755, 13098.410571524304, 13098.410571524304, 13123.0, 13134.934325328311, 7367.668454919542, 7381.188069312371, 7566.924330756622, 7694.807629809299, 7696.923030769621, 7118.9644051779405, 7166.390856777232, 7196.208417074117, 7343.0, 7386.852262954711, 4108.116754897711, 4371.0, 4380.7152535085115, 4473.4408198975125, 4492.797824097924, 1811.184966764961, 1837.8529717622557, 1847.2057015483203, 1861.088066847262, 1865.3844231403605, 816.5998660656236, 856.3448961544424, 873.6330741088758, 909.8544232922701, 956.0917128727737, 16501.752473712902, 16616.086115263533, 17670.558236044202, 17903.10484475771, 18086.18612162456, 826.557791581501, 841.6044459104211, 857.9184977427135, 881.405051590174, 922.6586163119689, 9386.67146649864, 9870.21038316933, 9875.753106172404, 9952.80094398108, 10045.49772511386, 433541.0, 435415.0, 436451.0, 437909.0, 438118.0, 78049.2195078042, 79312.0, 79369.60315198502, 81592.0, 87165.0, 911.968081117171, 916.2715641065369, 929.8233335666337, 933.6872147830417, 935.3826474526779, 15393.461228856937, 15421.383144674088, 16622.750658739886, 16653.25060372262, 16923.576910577332, 36691.44962825613, 38143.42784858174, 39154.0, 39732.2053558933, 40103.79948100241, 47052.0, 50735.746321268154, 51371.0, 52262.73868630632, 54202.0, 6844.726210951506, 7050.753223637148, 7111.111111111111, 7481.364084052699, 7694.730684426018, 12441.37793110359, 12504.187227830162, 12526.436310366238, 12591.748165036832, 12762.68093297675, 10135.290529662852, 10431.321964072311, 10626.628068017582, 10988.615398461336, 11240.494177762184, 9965.402075875181, 10318.380897146195, 10398.0, 10451.268411211142, 10983.450827458755, 10662.306950048222, 10760.623378181726, 10836.512356943798, 10856.077233435246, 10911.127109831354, 7164.319389657974, 7268.38218751412, 7357.301056399633, 7457.850842983002, 7489.325960663567, 10488.370697758159, 10703.732406689895, 10806.621768238074, 10942.562297508011, 11159.442027898735], "value": 8268.525210426167, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.9002479801616, 1372.9450821967118, 1423.3310344138254, 1484.8589384008521, 1712.1781544858468, 6573.276939536653, 7460.776176714694, 7725.188855170197, 7735.419843511738, 7914.960425197881, 7777.105632852224, 7831.843363132734, 7839.960800196005, 7843.647035883385, 7849.646765895534, 1829.423731524572, 2160.708304378912, 2209.712737344147, 2243.1588154442084, 2244.876531790751, 2204.184451752854, 2226.0, 2226.0539270809923, 2229.7435794883636, 2237.105157936822, 2151.752548456932, 2191.6822060801173, 2221.80003799658, 2232.274510784002, 2232.843700940935, 1830.2038613203167, 2102.6425507663735, 2140.785921407862, 2142.432255452291, 2147.634902066645, 2155.9784402155933, 2203.9449013774624, 2204.1844517528457, 2206.172685243034, 2207.5033117548583, 7898.328642065431, 7927.5243485391065, 7935.166807485189, 7946.404019698546, 7970.960145199295, 4601.815927362902, 4736.052789442126, 4741.336212930192, 4754.334393184957, 4755.524447555252, 2806.3966247256735, 2941.264683829043, 2974.7768917331287, 2975.1372102090368, 2989.925251868699, 902.2691619787993, 941.8587211918199, 946.2099147212017, 949.4255975135083, 952.7284723853669, 10668.733281667943, 10682.092022178123, 10720.410377429238, 10722.0886224671, 10729.731756706067, 4118.8352465901025, 4262.850800221977, 4336.956630433719, 4345.565443455629, 4368.4321038264825, 9139.588718507683, 9145.222656074175, 9148.725538233864, 9149.405288656217, 9149.679761208326, 4619.930701039487, 4651.395318608559, 4655.976720116378, 4657.6273898088075, 4663.696859704109, 3748.493953316322, 4116.115035267432, 4166.66666666666, 4205.747655140702, 4284.378765079046, 3618.7466877318334, 3669.7064234861145, 3818.1409182933617, 3821.464994900716, 3825.540935087807, 1195.7907366210877, 1233.0505510756668, 1237.0845574275152, 1246.046774217724, 1250.3435696259537, 683.2040672616446, 697.5326531224092, 716.2515171645642, 784.8587254294282, 836.1471299274828, 5205.6616319939085, 5337.452138879681, 5446.509814116748, 5460.754266058037, 5705.087186050295, 397.94826672532383, 403.1252182763352, 407.0779684015707, 411.5246889842237, 417.34268527069986, 3784.0729021389934, 3844.3464611016193, 3853.5761066282685, 3862.111714305669, 3930.390789427643, 9095.727128186165, 9395.389299695498, 9643.373180743229, 9658.227341812639, 9662.3719458235, 9594.280428967715, 9608.66369677058, 9611.519424028911, 9612.951935240279, 9613.375130616487, 7321.560706357635, 7324.780256592311, 7331.523450975669, 7335.156457007406, 7393.149787774367, 5432.56539476833, 5434.076207044733, 5468.835934921959, 5489.752961116839, 5702.144678298288, 8806.0, 8823.823523529622, 8914.910850891409, 8918.821623567623, 8982.461052336881, 8874.86687699672, 8962.68630597952, 9131.269498440242, 9173.816523669355, 9198.908010920073, 5942.138389933349, 6478.902816457851, 6861.965690171517, 6977.581345119309, 6984.965075174591, 7402.037735094404, 7617.885731714139, 7626.54240745557, 7630.1606823248285, 7631.465797394129, 6615.569987950767, 6687.09724187248, 6798.490113241428, 6808.659567021728, 7002.299770022957, 6314.0, 6685.39831415175, 6746.69639866217, 6846.760363387258, 6867.931320686729, 6133.84665383369, 6343.4608058313715, 6348.396902294275, 6355.809325720235, 6377.7767778127545, 4367.45406824147, 4412.117576484652, 4512.458504979423, 4586.220342541709, 4613.19269127908, 4401.0, 4630.652701047367, 4642.651801114997, 4673.836415725569, 4711.81152753886], "value": 4069.4230991571226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.2089494182828, 302.30017509465586, 305.6378191842665, 305.73095675805286, 307.8691556088663, 4765.90468190636, 4811.566958973692, 4813.892804654932, 4830.44449888263, 4830.468648448667, 4748.406449193851, 4761.833335833247, 4768.2847572864, 4768.5946694531, 4769.592970073828, 3575.410057340536, 3674.926501469969, 3712.83292251848, 3764.623537646241, 3792.317382871082, 3753.155540003491, 3763.5483741951, 3773.9622603774023, 3782.6595606395417, 3791.1659434924272, 3666.523351964241, 3712.721545884056, 3724.9627503724887, 3734.4585035169885, 3738.7101449999705, 3555.893323200295, 3604.6029390455315, 3734.1411475360546, 3750.6811920986747, 3778.886633401002, 3300.6864347886913, 3308.8676452941854, 3311.1391038329966, 3321.684439978198, 3325.118843506473, 4640.211164102111, 4641.9535804641855, 4644.674872758908, 4655.79048942795, 4677.18149323867, 4166.687498437629, 4273.743575385456, 4308.095299987007, 4308.569143085698, 4314.029343397742, 4909.484504127086, 4917.606591472673, 4925.581325587329, 4929.507049295078, 4934.876628084291, 3602.171500554887, 3604.567451905788, 3614.7831130132013, 3627.7460577759566, 3636.7636103653176, 5094.821681241138, 5227.849873027928, 5232.3459567554055, 5233.371995360543, 5237.633365664405, 3713.628637136265, 3717.386631205875, 3727.8816355093363, 3732.3654978653694, 3738.6074462181623, 3781.0925377909102, 3789.0906182515996, 3791.2038471921205, 3791.848326066982, 3792.829322680486, 4270.103278311527, 4272.188284225987, 4272.46594175728, 4278.251306021434, 4285.357196420562, 4825.30033145192, 4975.686305867789, 4989.760751211214, 4991.800327986913, 4999.325091112726, 1293.838270216223, 1309.4827543120414, 1315.967100822468, 1316.1708123881892, 1319.5484966536924, 816.8978877640295, 860.9668397922518, 862.8705694145929, 874.3660845886682, 876.3514998900768, 4292.48490181174, 4365.694401391936, 4393.340998850134, 4438.933415998763, 4445.732966104644, 5142.537171654569, 5153.845384638467, 5155.845324640267, 5169.560587350042, 5172.0, 4385.122975404932, 4389.078293558388, 4401.735895846259, 4415.955840441618, 4420.403245561873, 2585.2373549802855, 2704.905328313539, 2710.295323215978, 2721.2788611018095, 2729.3586007288272, 4877.3903262092235, 4887.560119589254, 4897.48178064794, 4900.5099490051425, 4916.139675556763, 5015.749212539358, 5250.579953603703, 5262.97368513155, 5264.552513036435, 5268.473152684701, 4912.975435122801, 4931.137051016058, 4934.087193869145, 4944.283078953532, 4950.331705219821, 5189.273501709839, 5195.038917800219, 5197.896042079062, 5216.739163041757, 5219.451957544403, 4854.854354369375, 5057.393112826484, 5115.2071428928575, 5126.2054381570915, 5136.640435169502, 4929.69363118781, 4931.50684931504, 4933.654644174873, 4935.680230291757, 4948.307236986897, 3751.343514884939, 3794.886153415402, 3814.5041144652128, 3830.32969230378, 3838.673712734449, 4996.375453068366, 5177.870553236199, 5185.818496352609, 5186.507281808223, 5212.296339994053, 4976.377952755905, 4980.153373926513, 4985.177445721415, 4987.950120498748, 5012.924806127838, 4992.452339774536, 4995.900081998267, 5016.165866285537, 5025.597952163891, 5033.924491132562, 4761.262004389325, 4900.215965445512, 4947.2579113133315, 4959.181735013683, 4961.479044700254, 4767.928481072718, 4859.878503037452, 4964.4539100698175, 4994.1759609663995, 5033.842216290201, 4951.158303088411, 4952.702837829742, 4954.752262386938, 4967.105920934267, 4967.577755890789], "value": 3794.7493202966684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.3972446726675, 649.4512137244027, 657.62186742623, 701.7578935267334, 734.9154847192572, 13276.800847987275, 14040.508582199638, 14068.22624755638, 14077.929610351946, 14082.225477598753, 13374.866251337484, 13505.459781608724, 13533.255670938095, 13534.796978045313, 13581.456741730319, 614.0850133301371, 628.6196850905205, 749.2769477454256, 767.6392095715005, 779.3258831111076, 761.6344154805697, 771.6026246483037, 771.7993321736361, 772.112071118213, 772.428402981795, 825.405707890321, 826.6652005937605, 826.7106512720529, 826.8222332198576, 827.4993628854546, 639.2967735490922, 768.2739810878721, 804.6620419423856, 822.2928281677753, 822.9259366657, 590.8700085981077, 591.8816236752667, 592.7569696424489, 593.6111846740392, 594.5659668442019, 13719.1082579633, 13766.449342026306, 13781.173129612154, 13794.793078103836, 13802.102863313852, 11892.940535297355, 11963.46164422603, 11964.102692298113, 12253.754924901541, 12996.155249908727, 11347.829782553268, 11373.601923932705, 11566.595169169039, 11586.246893951951, 12045.0, 3920.7843568603716, 3986.920261594752, 4015.19696060789, 4015.4779878615886, 4031.07285324377, 64566.67716661434, 66071.66964165197, 66362.66818665924, 66426.66786666083, 66755.3324466754, 9704.514774261259, 9820.361676491148, 9959.203263738884, 10120.038596333337, 10242.129418999319, 16471.423500177436, 17044.14779261032, 19111.426657200307, 19140.425787226406, 19253.422397328104, 6772.5936443813525, 6915.619640919697, 6957.443404527626, 7014.473914456436, 7153.391961683211, 3688.66801987817, 3825.407061905409, 3835.4438606401914, 3850.9037274068373, 3900.8244628991765, 2978.4936560784718, 3093.752499800011, 3118.9064328070194, 3119.298157914451, 3128.296133370019, 529.4282175250797, 532.0476347338298, 533.757140501071, 537.3793268774572, 543.6765124750767, 407.7614595461629, 490.225443798797, 507.50264740553723, 539.398570593785, 552.4668694709625, 7758.262965018314, 7870.72452464161, 7971.521708697497, 8348.123447037973, 8551.871721924175, 555.1201345866766, 567.897778399892, 581.520245797219, 584.3951510186936, 587.1193210184723, 4033.576474470197, 4057.5536690963972, 4317.870463886088, 4327.740335579875, 4359.542248063971, 92781.5360923191, 95627.56558651626, 99971.50014249881, 102279.488602558, 102576.48711756543, 83783.5810820942, 83968.58015709883, 85767.5711621438, 85949.57025214958, 86501.0, 73062.63468682622, 75021.97763992418, 75962.24037759552, 76747.61626191833, 77503.0, 8019.992502811446, 8035.839283214186, 8124.350051995708, 8185.508869467851, 8515.1059138792, 44766.77616611896, 45796.7710161447, 46957.2956405661, 47407.28889066598, 47500.52499474961, 59710.40289597048, 66399.0, 67195.0, 67862.32137678757, 68020.6598967002, 9584.520773961134, 9862.358946668444, 9897.911229764864, 9912.801743964936, 10190.541425635716, 11014.834777478183, 11292.887071129184, 11293.943530282297, 11371.772564548828, 11392.772144556897, 10136.421082077366, 10537.26239163282, 10695.942986821748, 10803.297785643908, 11051.171162163031, 10639.680809575726, 10702.625408110678, 10721.195910306602, 10743.623973161215, 11161.44192790341, 10215.846762298423, 10489.318194317346, 11601.187916846078, 11673.182877198515, 11919.642410727693, 10392.272540922366, 10420.166386688896, 10648.467576620984, 10780.17436904643, 10808.459577021275, 10205.74485637865, 10758.462076895967, 10804.891951080388, 10929.945350273198, 11063.391513466837], "value": 6573.371435701117, "unit": "score"}], "name": "dromaeo_css", "value": 6376.166476533619, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 117.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 125.0, 124.0, 176.0, 122.0, 123.0, 141.0, 122.0, 165.0, 123.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 161.0, 164.0, 162.0, 164.0, 168.0, 161.0, 160.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 109.0, 77.0, 76.0, 75.0, 75.0, 102.0, 76.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 123.0, 85.0, 84.0, 84.0, 113.0, 84.0, 84.0, 122.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 65.0, 65.0, 64.0, 72.0, 65.0, 64.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 88.0, 79.0, 79.0, 80.0, 78.0, 79.0, 84.0, 84.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 130.0, 131.0, 129.0, 127.0, 126.0, 127.0, 130.0, 176.0, 128.0], "value": 134.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 168.0, 167.0, 161.0, 164.0, 169.0, 166.0, 162.0, 165.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 66.0, 66.0, 66.0, 67.0, 65.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1524.9999999999998, "alertThreshold": 2.0}]} 15:14:33 INFO - Return code: 0 15:14:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:14:34 INFO - # TBPL SUCCESS # 15:14:34 INFO - Running post-action listener: _resource_record_post_action 15:14:34 INFO - [mozharness: 2016-07-20 22:14:34.021214Z] Finished run-tests step (success) 15:14:34 INFO - Running post-run listener: _resource_record_post_run 15:14:34 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 547729408; Read time: 0; Write time: 467060 15:14:34 INFO - TinderboxPrint: CPU usage
13.0% 15:14:34 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:14:34 INFO - TinderboxPrint: I/O write bytes / time
547,729,408 / 467,060 15:14:34 INFO - TinderboxPrint: CPU idle
8,334.4 (87.0%) 15:14:34 INFO - TinderboxPrint: CPU user
1,198.9 (12.5%) 15:14:34 INFO - TinderboxPrint: Swap in / out
0 / 0 15:14:34 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 259457024; Read time: 0; Write time: 282812 15:14:34 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288272384; Read time: 0; Write time: 184248 15:14:34 INFO - Running post-run listener: _upload_blobber_files 15:14:34 INFO - Blob upload gear active. 15:14:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:14:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:14:35 INFO - (blobuploader) - INFO - Open directory for files ... 15:14:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:14:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:14:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:36 INFO - (blobuploader) - INFO - Done attempting. 15:14:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:14:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:14:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:47 INFO - (blobuploader) - INFO - Done attempting. 15:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:14:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:48 INFO - (blobuploader) - INFO - Done attempting. 15:14:48 INFO - (blobuploader) - INFO - Iteration through files over. 15:14:48 INFO - Return code: 0 15:14:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:14:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:14:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e04176a24c3c9aa92c6c6be62cbe2c219200fb089155ec1660401d7fb297f2c7ce49bfa44bea720b39eb12e32d4f340b208ff49d76b3f4da85b33e9ae7399706", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/96d62b520c5a97215559ecaeb71e8b0792a7803a7f9f04b698ab193c180b1ec8621ffa90a208e232c4ac7f90d6c350f93afc8863dae1c5e72c4d59d23416516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb77226d1ebf67f216fa954c803ea6f3afb65eff3114bf407952a066e9ee3002b713b114a0ea87e0934116a43114644f8e43bc098dfdf87ff5ea2ff3698be547"} 15:14:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:14:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:14:48 INFO - Contents: 15:14:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e04176a24c3c9aa92c6c6be62cbe2c219200fb089155ec1660401d7fb297f2c7ce49bfa44bea720b39eb12e32d4f340b208ff49d76b3f4da85b33e9ae7399706", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/96d62b520c5a97215559ecaeb71e8b0792a7803a7f9f04b698ab193c180b1ec8621ffa90a208e232c4ac7f90d6c350f93afc8863dae1c5e72c4d59d23416516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb77226d1ebf67f216fa954c803ea6f3afb65eff3114bf407952a066e9ee3002b713b114a0ea87e0934116a43114644f8e43bc098dfdf87ff5ea2ff3698be547"} 15:14:48 INFO - Running post-run listener: copy_logs_to_upload_dir 15:14:48 INFO - Copying logs to upload dir... 15:14:48 INFO - mkdir: /builds/slave/test/build/upload/logs 15:14:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.982188 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-07-20 15:14:48.878169) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 15:14:48.881340) ========= 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=eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e04176a24c3c9aa92c6c6be62cbe2c219200fb089155ec1660401d7fb297f2c7ce49bfa44bea720b39eb12e32d4f340b208ff49d76b3f4da85b33e9ae7399706", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/96d62b520c5a97215559ecaeb71e8b0792a7803a7f9f04b698ab193c180b1ec8621ffa90a208e232c4ac7f90d6c350f93afc8863dae1c5e72c4d59d23416516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb77226d1ebf67f216fa954c803ea6f3afb65eff3114bf407952a066e9ee3002b713b114a0ea87e0934116a43114644f8e43bc098dfdf87ff5ea2ff3698be547"} build_url:https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/dO7BcR5xRniIn3ByD2kaRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e04176a24c3c9aa92c6c6be62cbe2c219200fb089155ec1660401d7fb297f2c7ce49bfa44bea720b39eb12e32d4f340b208ff49d76b3f4da85b33e9ae7399706", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/96d62b520c5a97215559ecaeb71e8b0792a7803a7f9f04b698ab193c180b1ec8621ffa90a208e232c4ac7f90d6c350f93afc8863dae1c5e72c4d59d23416516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb77226d1ebf67f216fa954c803ea6f3afb65eff3114bf407952a066e9ee3002b713b114a0ea87e0934116a43114644f8e43bc098dfdf87ff5ea2ff3698be547"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 15:14:48.909383) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 15:14:48.910139) ========= 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=eca2ab57546de7b41f256b19000001ef-1469051628.9345-269037415 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 15:14:48.994217) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 15:14:48.994812) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 15:14:48.995177) ========= ========= Total master_lag: 1.77 =========