builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-049 starttime: 1470695282.03 results: success (0) buildid: 20160808143420 builduid: 92dd9fad2072470cbe047aef47ff9955 revision: a8df38f8ada3aca17303f21b9d447dafd7bbb9d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.034928) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.035571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.035843) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.079918) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.080267) ========= 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=db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 15:28:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2016-08-08 15:28:02 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164153 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.258779) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.259137) ========= 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=db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015342 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.308810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.309149) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a8df38f8ada3aca17303f21b9d447dafd7bbb9d6 --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 a8df38f8ada3aca17303f21b9d447dafd7bbb9d6 --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=db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 15:28:02,363 truncating revision to first 12 chars 2016-08-08 15:28:02,363 Setting DEBUG logging. 2016-08-08 15:28:02,363 attempt 1/10 2016-08-08 15:28:02,363 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a8df38f8ada3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 15:28:02,685 unpacking tar archive at: autoland-a8df38f8ada3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647864 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.970729) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.971068) ========= script_repo_revision: a8df38f8ada3aca17303f21b9d447dafd7bbb9d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.971481) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.971745) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 15:28:02.985648) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-08 15:28:02.986091) ========= /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=db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:28:03 INFO - MultiFileLogger online at 20160808 15:28:03 in /builds/slave/test 15:28:03 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 15:28:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:28:03 INFO - {'append_to_log': False, 15:28:03 INFO - 'base_work_dir': '/builds/slave/test', 15:28:03 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:28:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:28:03 INFO - 'branch': 'Autoland-Non-PGO', 15:28:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:28:03 INFO - 'config_files': ('talos/linux_config.py',), 15:28:03 INFO - 'default_actions': ('clobber', 15:28:03 INFO - 'read-buildbot-config', 15:28:03 INFO - 'download-and-extract', 15:28:03 INFO - 'populate-webroot', 15:28:03 INFO - 'create-virtualenv', 15:28:03 INFO - 'install', 15:28:03 INFO - 'run-tests'), 15:28:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:28:03 INFO - 'download_minidump_stackwalk': True, 15:28:03 INFO - 'download_symbols': 'ondemand', 15:28:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:28:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:28:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:28:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:28:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:28:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:28:03 INFO - 'installer_path': 'installer.exe', 15:28:03 INFO - 'log_level': 'info', 15:28:03 INFO - 'log_name': 'talos', 15:28:03 INFO - 'log_to_console': True, 15:28:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:28:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:28:03 INFO - 'opt_config_files': (), 15:28:03 INFO - 'pip_index': False, 15:28:03 INFO - 'sps_profile': False, 15:28:03 INFO - 'sps_profile_interval': 0, 15:28:03 INFO - 'suite': 'dromaeojs', 15:28:03 INFO - 'system_bits': '32', 15:28:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:28:03 INFO - 'title': 'talos-linux64-ix-049', 15:28:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:28:03 INFO - 'use_talos_json': True, 15:28:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:28:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:28:03 INFO - 'work_dir': 'build'} 15:28:03 INFO - [mozharness: 2016-08-08 22:28:03.133398Z] Running clobber step. 15:28:03 INFO - Running pre-action listener: _resource_record_pre_action 15:28:03 INFO - Running main action method: clobber 15:28:03 INFO - rmtree: /builds/slave/test/build 15:28:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:28:03 INFO - Running post-action listener: _resource_record_post_action 15:28:03 INFO - [mozharness: 2016-08-08 22:28:03.579532Z] Finished clobber step (success) 15:28:03 INFO - [mozharness: 2016-08-08 22:28:03.579632Z] Running read-buildbot-config step. 15:28:03 INFO - Running pre-action listener: _resource_record_pre_action 15:28:03 INFO - Running main action method: read_buildbot_config 15:28:03 INFO - Using buildbot properties: 15:28:03 INFO - { 15:28:03 INFO - "project": "", 15:28:03 INFO - "product": "firefox", 15:28:03 INFO - "script_repo_revision": "production", 15:28:03 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:28:03 INFO - "repository": "", 15:28:03 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 15:28:03 INFO - "buildid": "20160808143420", 15:28:03 INFO - "pgo_build": "False", 15:28:03 INFO - "basedir": "/builds/slave/test", 15:28:03 INFO - "buildnumber": 198, 15:28:03 INFO - "slavename": "talos-linux64-ix-049", 15:28:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:28:03 INFO - "platform": "ubuntu64_hw", 15:28:03 INFO - "scheduler": "tests-autoland-linux64-talos", 15:28:03 INFO - "branch": "autoland", 15:28:03 INFO - "repo_path": "integration/autoland", 15:28:03 INFO - "revision": "a8df38f8ada3aca17303f21b9d447dafd7bbb9d6", 15:28:03 INFO - "stage_platform": "linux64", 15:28:03 INFO - "builduid": "92dd9fad2072470cbe047aef47ff9955", 15:28:03 INFO - "slavebuilddir": "test" 15:28:03 INFO - } 15:28:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:28:03 INFO - Running post-action listener: _resource_record_post_action 15:28:03 INFO - [mozharness: 2016-08-08 22:28:03.582349Z] Finished read-buildbot-config step (success) 15:28:03 INFO - [mozharness: 2016-08-08 22:28:03.582438Z] Running download-and-extract step. 15:28:03 INFO - Running pre-action listener: _resource_record_pre_action 15:28:03 INFO - Running main action method: download_and_extract 15:28:03 INFO - mkdir: /builds/slave/test/build/tests 15:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:03 INFO - trying https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:28:03 INFO - Downloading https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:28:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:28:04 INFO - Downloaded 1361 bytes. 15:28:04 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:28:04 INFO - Using the following test package requirements: 15:28:04 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:28:04 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:28:04 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:28:04 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'jsshell-linux-x86_64.zip'], 15:28:04 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:28:04 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:28:04 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:28:04 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:28:04 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:28:04 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:28:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:28:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:04 INFO - trying https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:28:04 INFO - Downloading https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:28:05 INFO - Downloaded 20258190 bytes. 15:28:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:28:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:28:06 INFO - Return code: 0 15:28:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:28:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:06 INFO - trying https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:28:06 INFO - Downloading https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:28:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:28:07 INFO - Downloaded 20258190 bytes. 15:28:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:28:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:28:08 INFO - Return code: 0 15:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:08 INFO - trying https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:28:08 INFO - Downloading https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:28:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:28:09 INFO - Downloaded 14413723 bytes. 15:28:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:28:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:28:09 INFO - Return code: 0 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:09 INFO - trying https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:28:09 INFO - Downloading https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:28:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:28:11 INFO - Downloaded 56138393 bytes. 15:28:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:28:11 INFO - mkdir: /builds/slave/test/properties 15:28:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:28:11 INFO - Writing to file /builds/slave/test/properties/build_url 15:28:11 INFO - Contents: 15:28:11 INFO - build_url:https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:28:11 INFO - Running post-action listener: _resource_record_post_action 15:28:11 INFO - Running post-action listener: set_extra_try_arguments 15:28:11 INFO - [mozharness: 2016-08-08 22:28:11.977673Z] Finished download-and-extract step (success) 15:28:11 INFO - [mozharness: 2016-08-08 22:28:11.977917Z] Running populate-webroot step. 15:28:11 INFO - Running pre-action listener: _resource_record_pre_action 15:28:11 INFO - Running main action method: populate_webroot 15:28:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:28:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:28:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:28:11 INFO - '--tppagecycles', 15:28:11 INFO - '7'], 15:28:11 INFO - 'tests': ['tsvgm']}}, 15:28:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:28:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:28:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:28:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:28:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:28:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:28:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['damp', 'tps']}, 15:28:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'tests': ['damp', 'tps']}, 15:28:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['dromaeo_dom']}, 15:28:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:28:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['basic_compositor_video']}, 15:28:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:28:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['a11yr', 15:28:11 INFO - 'ts_paint', 15:28:11 INFO - 'tpaint', 15:28:11 INFO - 'sessionrestore', 15:28:11 INFO - 'sessionrestore_no_auto_restore', 15:28:11 INFO - 'tabpaint']}, 15:28:11 INFO - 'other-e10s': {'tests': ['a11yr', 15:28:11 INFO - 'ts_paint', 15:28:11 INFO - 'tpaint', 15:28:11 INFO - 'sessionrestore', 15:28:11 INFO - 'sessionrestore_no_auto_restore', 15:28:11 INFO - 'tabpaint']}, 15:28:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:28:11 INFO - 'ts_paint', 15:28:11 INFO - 'tpaint', 15:28:11 INFO - 'sessionrestore', 15:28:11 INFO - 'sessionrestore_no_auto_restore', 15:28:11 INFO - 'tabpaint']}, 15:28:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:28:11 INFO - 'ts_paint', 15:28:11 INFO - 'tpaint', 15:28:11 INFO - 'sessionrestore', 15:28:11 INFO - 'sessionrestore_no_auto_restore', 15:28:11 INFO - 'tabpaint']}, 15:28:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['a11yr', 15:28:11 INFO - 'ts_paint', 15:28:11 INFO - 'tpaint', 15:28:11 INFO - 'sessionrestore', 15:28:11 INFO - 'sessionrestore_no_auto_restore', 15:28:11 INFO - 'tabpaint']}, 15:28:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['a11yr', 15:28:11 INFO - 'ts_paint', 15:28:11 INFO - 'tpaint', 15:28:11 INFO - 'sessionrestore', 15:28:11 INFO - 'sessionrestore_no_auto_restore', 15:28:11 INFO - 'tabpaint']}, 15:28:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['tsvgx', 15:28:11 INFO - 'tsvgr_opacity', 15:28:11 INFO - 'tart', 15:28:11 INFO - 'tscrollx', 15:28:11 INFO - 'cart']}, 15:28:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:28:11 INFO - 'tsvgr_opacity', 15:28:11 INFO - 'tart', 15:28:11 INFO - 'tscrollx', 15:28:11 INFO - 'cart']}, 15:28:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'talos_options': ['--disable-e10s'], 15:28:11 INFO - 'tests': ['tp5o']}, 15:28:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'tests': ['tp5o']}, 15:28:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'talos_options': ['--disable-e10s', 15:28:11 INFO - '--xperf_path', 15:28:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:28:11 INFO - 'tests': ['tp5n']}, 15:28:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:28:11 INFO - 'talos_options': ['--xperf_path', 15:28:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:28:11 INFO - 'tests': ['tp5n']}}, 15:28:11 INFO - 'talos.zip': {'path': '', 15:28:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:28:11 INFO - Running post-action listener: _resource_record_post_action 15:28:11 INFO - [mozharness: 2016-08-08 22:28:11.998687Z] Finished populate-webroot step (success) 15:28:11 INFO - [mozharness: 2016-08-08 22:28:11.998775Z] Running create-virtualenv step. 15:28:11 INFO - Running pre-action listener: _resource_record_pre_action 15:28:11 INFO - Running main action method: create_virtualenv 15:28:11 INFO - Creating virtualenv /builds/slave/test/build/venv 15:28: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 15:28:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:28:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:28:12 INFO - Using real prefix '/usr' 15:28:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:28:12 INFO - Installing distribute.............................................................................................................................................................................................done. 15:28:14 INFO - Installing pip.................done. 15:28:14 INFO - Return code: 0 15:28:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28: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 15:28: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 15:28:14 INFO - Using env: {'DISPLAY': ':0', 15:28:14 INFO - 'HOME': '/home/cltbld', 15:28:14 INFO - 'LANG': 'en_US.UTF-8', 15:28:14 INFO - 'LANGUAGE': 'en_US:en', 15:28:14 INFO - 'LOGNAME': 'cltbld', 15:28:14 INFO - 'MAIL': '/var/mail/cltbld', 15:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:14 INFO - 'MOZ_NO_REMOTE': '1', 15:28:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:14 INFO - 'NO_EM_RESTART': '1', 15:28:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:14 INFO - 'PWD': '/builds/slave/test', 15:28:14 INFO - 'SHELL': '/bin/bash', 15:28:14 INFO - 'SHLVL': '1', 15:28:14 INFO - 'TERM': 'linux', 15:28:14 INFO - 'TMOUT': '86400', 15:28:14 INFO - 'USER': 'cltbld', 15:28:14 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:14 INFO - '_': '/tools/buildbot/bin/python'} 15:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28: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 15:28:14 INFO - Cleaning up... 15:28:14 INFO - Return code: 0 15:28:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28: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 15:28: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 15:28:14 INFO - Using env: {'DISPLAY': ':0', 15:28:14 INFO - 'HOME': '/home/cltbld', 15:28:14 INFO - 'LANG': 'en_US.UTF-8', 15:28:14 INFO - 'LANGUAGE': 'en_US:en', 15:28:14 INFO - 'LOGNAME': 'cltbld', 15:28:14 INFO - 'MAIL': '/var/mail/cltbld', 15:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:14 INFO - 'MOZ_NO_REMOTE': '1', 15:28:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:14 INFO - 'NO_EM_RESTART': '1', 15:28:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:14 INFO - 'PWD': '/builds/slave/test', 15:28:14 INFO - 'SHELL': '/bin/bash', 15:28:14 INFO - 'SHLVL': '1', 15:28:14 INFO - 'TERM': 'linux', 15:28:14 INFO - 'TMOUT': '86400', 15:28:14 INFO - 'USER': 'cltbld', 15:28:14 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:14 INFO - '_': '/tools/buildbot/bin/python'} 15:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:15 INFO - Downloading/unpacking psutil>=3.1.1 15:28:15 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 15:28:15 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 15:28:15 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 15:28:15 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 15:28:15 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 15:28:15 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 15:28:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:28:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:28:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:28:17 INFO - Installing collected packages: psutil 15:28:17 INFO - Running setup.py install for psutil 15:28:17 INFO - building 'psutil._psutil_linux' extension 15:28:17 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 15:28: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:28:17 INFO - building 'psutil._psutil_posix' extension 15:28:17 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 15:28: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 15:28:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:28:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:28:17 INFO - Successfully installed psutil 15:28:17 INFO - Cleaning up... 15:28:17 INFO - Return code: 0 15:28:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28: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 15:28: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 15:28:17 INFO - Using env: {'DISPLAY': ':0', 15:28:17 INFO - 'HOME': '/home/cltbld', 15:28:17 INFO - 'LANG': 'en_US.UTF-8', 15:28:17 INFO - 'LANGUAGE': 'en_US:en', 15:28:17 INFO - 'LOGNAME': 'cltbld', 15:28:17 INFO - 'MAIL': '/var/mail/cltbld', 15:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:17 INFO - 'MOZ_NO_REMOTE': '1', 15:28:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:17 INFO - 'NO_EM_RESTART': '1', 15:28:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:17 INFO - 'PWD': '/builds/slave/test', 15:28:17 INFO - 'SHELL': '/bin/bash', 15:28:17 INFO - 'SHLVL': '1', 15:28:17 INFO - 'TERM': 'linux', 15:28:17 INFO - 'TMOUT': '86400', 15:28:17 INFO - 'USER': 'cltbld', 15:28:17 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:17 INFO - '_': '/tools/buildbot/bin/python'} 15:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:28:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:28:19 INFO - Installing collected packages: mozsystemmonitor 15:28:19 INFO - Running setup.py install for mozsystemmonitor 15:28:19 INFO - Successfully installed mozsystemmonitor 15:28:19 INFO - Cleaning up... 15:28:19 INFO - Return code: 0 15:28:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28: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 15:28: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 15:28:19 INFO - Using env: {'DISPLAY': ':0', 15:28:19 INFO - 'HOME': '/home/cltbld', 15:28:19 INFO - 'LANG': 'en_US.UTF-8', 15:28:19 INFO - 'LANGUAGE': 'en_US:en', 15:28:19 INFO - 'LOGNAME': 'cltbld', 15:28:19 INFO - 'MAIL': '/var/mail/cltbld', 15:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:19 INFO - 'MOZ_NO_REMOTE': '1', 15:28:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:19 INFO - 'NO_EM_RESTART': '1', 15:28:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:19 INFO - 'PWD': '/builds/slave/test', 15:28:19 INFO - 'SHELL': '/bin/bash', 15:28:19 INFO - 'SHLVL': '1', 15:28:19 INFO - 'TERM': 'linux', 15:28:19 INFO - 'TMOUT': '86400', 15:28:19 INFO - 'USER': 'cltbld', 15:28:19 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:19 INFO - '_': '/tools/buildbot/bin/python'} 15:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:20 INFO - Downloading/unpacking jsonschema==2.5.1 15:28:20 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 15:28:20 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 15:28:20 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 15:28:20 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 15:28:20 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 15:28:20 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 15:28:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:28:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:28:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:28:22 INFO - warning: no files found matching '*.txt' 15:28:22 INFO - no previously-included directories found matching 'build' 15:28:22 INFO - no previously-included directories found matching 'dist' 15:28:22 INFO - no previously-included directories found matching '.git*' 15:28:22 INFO - Installing collected packages: jsonschema, functools32 15:28:22 INFO - Running setup.py install for functools32 15:28:22 INFO - warning: no files found matching '*.txt' 15:28:22 INFO - no previously-included directories found matching 'build' 15:28:22 INFO - no previously-included directories found matching 'dist' 15:28:22 INFO - no previously-included directories found matching '.git*' 15:28:22 INFO - Successfully installed jsonschema functools32 15:28:22 INFO - Cleaning up... 15:28:22 INFO - Return code: 0 15:28:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:28: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 functools32==3.2.3-2 15:28:22 INFO - Using env: {'DISPLAY': ':0', 15:28:22 INFO - 'HOME': '/home/cltbld', 15:28:22 INFO - 'LANG': 'en_US.UTF-8', 15:28:22 INFO - 'LANGUAGE': 'en_US:en', 15:28:22 INFO - 'LOGNAME': 'cltbld', 15:28:22 INFO - 'MAIL': '/var/mail/cltbld', 15:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:22 INFO - 'MOZ_NO_REMOTE': '1', 15:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:22 INFO - 'NO_EM_RESTART': '1', 15:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:22 INFO - 'PWD': '/builds/slave/test', 15:28:22 INFO - 'SHELL': '/bin/bash', 15:28:22 INFO - 'SHLVL': '1', 15:28:22 INFO - 'TERM': 'linux', 15:28:22 INFO - 'TMOUT': '86400', 15:28:22 INFO - 'USER': 'cltbld', 15:28:22 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:22 INFO - '_': '/tools/buildbot/bin/python'} 15:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:28:22 INFO - Cleaning up... 15:28:22 INFO - Return code: 0 15:28:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28: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 15:28: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 15:28:22 INFO - Using env: {'DISPLAY': ':0', 15:28:22 INFO - 'HOME': '/home/cltbld', 15:28:22 INFO - 'LANG': 'en_US.UTF-8', 15:28:22 INFO - 'LANGUAGE': 'en_US:en', 15:28:22 INFO - 'LOGNAME': 'cltbld', 15:28:22 INFO - 'MAIL': '/var/mail/cltbld', 15:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:22 INFO - 'MOZ_NO_REMOTE': '1', 15:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:22 INFO - 'NO_EM_RESTART': '1', 15:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:22 INFO - 'PWD': '/builds/slave/test', 15:28:22 INFO - 'SHELL': '/bin/bash', 15:28:22 INFO - 'SHLVL': '1', 15:28:22 INFO - 'TERM': 'linux', 15:28:22 INFO - 'TMOUT': '86400', 15:28:22 INFO - 'USER': 'cltbld', 15:28:22 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:22 INFO - '_': '/tools/buildbot/bin/python'} 15:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:24 INFO - Downloading blobuploader-1.2.4.tar.gz 15:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:28:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:28:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:28:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:25 INFO - Downloading docopt-0.6.1.tar.gz 15:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:28:25 INFO - Installing collected packages: blobuploader, requests, docopt 15:28:25 INFO - Running setup.py install for blobuploader 15:28:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:28:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:28:25 INFO - Running setup.py install for requests 15:28:25 INFO - Running setup.py install for docopt 15:28:26 INFO - Successfully installed blobuploader requests docopt 15:28:26 INFO - Cleaning up... 15:28:26 INFO - Return code: 0 15:28:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:26 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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28:26 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 15:28:26 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 15:28:26 INFO - Using env: {'DISPLAY': ':0', 15:28:26 INFO - 'HOME': '/home/cltbld', 15:28:26 INFO - 'LANG': 'en_US.UTF-8', 15:28:26 INFO - 'LANGUAGE': 'en_US:en', 15:28:26 INFO - 'LOGNAME': 'cltbld', 15:28:26 INFO - 'MAIL': '/var/mail/cltbld', 15:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:26 INFO - 'MOZ_NO_REMOTE': '1', 15:28:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:26 INFO - 'NO_EM_RESTART': '1', 15:28:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:26 INFO - 'PWD': '/builds/slave/test', 15:28:26 INFO - 'SHELL': '/bin/bash', 15:28:26 INFO - 'SHLVL': '1', 15:28:26 INFO - 'TERM': 'linux', 15:28:26 INFO - 'TMOUT': '86400', 15:28:26 INFO - 'USER': 'cltbld', 15:28:26 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:26 INFO - '_': '/tools/buildbot/bin/python'} 15:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:28:26 INFO - Running setup.py (path:/tmp/pip-ZgIfQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:28:26 INFO - Running setup.py (path:/tmp/pip-dnldRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:28:26 INFO - Running setup.py (path:/tmp/pip-EAqGt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:28:26 INFO - Running setup.py (path:/tmp/pip-kg8JYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:28:26 INFO - Running setup.py (path:/tmp/pip-VRofRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:28:26 INFO - Running setup.py (path:/tmp/pip-OlNIPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:28:26 INFO - Running setup.py (path:/tmp/pip-ugl3Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:28:26 INFO - Running setup.py (path:/tmp/pip-LfSI1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:28:26 INFO - Running setup.py (path:/tmp/pip-i8nIO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:28:27 INFO - Running setup.py (path:/tmp/pip-NHQ5N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:28:27 INFO - Running setup.py (path:/tmp/pip-msVKTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:28:27 INFO - Running setup.py (path:/tmp/pip-2j9ljt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:28:27 INFO - Running setup.py (path:/tmp/pip-gxj9gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:28:27 INFO - Running setup.py (path:/tmp/pip-IPzGxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:27 INFO - Running setup.py (path:/tmp/pip-7Mr_PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:28:27 INFO - Running setup.py (path:/tmp/pip-uJuoqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:28:27 INFO - Running setup.py (path:/tmp/pip-farywM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:28:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:28:27 INFO - Running setup.py install for manifestparser 15:28:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:28:27 INFO - Running setup.py install for mozcrash 15:28:27 INFO - Running setup.py install for mozdebug 15:28:27 INFO - Running setup.py install for mozdevice 15:28:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for mozfile 15:28:28 INFO - Running setup.py install for mozhttpd 15:28:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for mozinfo 15:28:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for mozInstall 15:28:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for mozleak 15:28:28 INFO - Running setup.py install for mozlog 15:28:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for moznetwork 15:28:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for mozprocess 15:28:28 INFO - Running setup.py install for mozprofile 15:28:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:28:28 INFO - Running setup.py install for mozrunner 15:28:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:28:29 INFO - Running setup.py install for mozscreenshot 15:28:29 INFO - Running setup.py install for moztest 15:28:29 INFO - Running setup.py install for mozversion 15:28:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:28:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:28:29 INFO - Cleaning up... 15:28:29 INFO - Return code: 0 15:28:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:29 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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28:29 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 15:28:29 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 15:28:29 INFO - Using env: {'DISPLAY': ':0', 15:28:29 INFO - 'HOME': '/home/cltbld', 15:28:29 INFO - 'LANG': 'en_US.UTF-8', 15:28:29 INFO - 'LANGUAGE': 'en_US:en', 15:28:29 INFO - 'LOGNAME': 'cltbld', 15:28:29 INFO - 'MAIL': '/var/mail/cltbld', 15:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:29 INFO - 'MOZ_NO_REMOTE': '1', 15:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:29 INFO - 'NO_EM_RESTART': '1', 15:28:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:29 INFO - 'PWD': '/builds/slave/test', 15:28:29 INFO - 'SHELL': '/bin/bash', 15:28:29 INFO - 'SHLVL': '1', 15:28:29 INFO - 'TERM': 'linux', 15:28:29 INFO - 'TMOUT': '86400', 15:28:29 INFO - 'USER': 'cltbld', 15:28:29 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:29 INFO - '_': '/tools/buildbot/bin/python'} 15:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:28:29 INFO - Running setup.py (path:/tmp/pip-yQiMj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:28: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)) 15:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:28:29 INFO - Running setup.py (path:/tmp/pip-kDhEys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:28: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)) 15:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:28:29 INFO - Running setup.py (path:/tmp/pip-fAiwTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:28: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)) 15:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:28:29 INFO - Running setup.py (path:/tmp/pip-pAGUjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:28: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)) 15:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:28:29 INFO - Running setup.py (path:/tmp/pip-855yo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:28: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)) 15:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:28:29 INFO - Running setup.py (path:/tmp/pip-DxfwYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:28:30 INFO - Running setup.py (path:/tmp/pip-6VdPXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:28:30 INFO - Running setup.py (path:/tmp/pip-LOWCoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:28:30 INFO - Running setup.py (path:/tmp/pip-XY7J2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:28:30 INFO - Running setup.py (path:/tmp/pip-vVjzzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:28:30 INFO - Running setup.py (path:/tmp/pip-A6cERZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:28:30 INFO - Running setup.py (path:/tmp/pip-GNfjZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:28:30 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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:28:30 INFO - Running setup.py (path:/tmp/pip-bPMNsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:28: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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:28:30 INFO - Running setup.py (path:/tmp/pip-PcYnnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:28: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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:30 INFO - Running setup.py (path:/tmp/pip-1ankrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:28: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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:28:30 INFO - Running setup.py (path:/tmp/pip-4eaAdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:28: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)) 15:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:28:30 INFO - Running setup.py (path:/tmp/pip-tphvC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:28: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)) 15:28: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)) 15:28: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)) 15:28: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)) 15:28: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)) 15:28: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)) 15:28:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:32 INFO - Downloading blessings-1.6.tar.gz 15:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:28:32 INFO - Installing collected packages: blessings 15:28:32 INFO - Running setup.py install for blessings 15:28:32 INFO - Successfully installed blessings 15:28:32 INFO - Cleaning up... 15:28:32 INFO - Return code: 0 15:28:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:28:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:28:33 INFO - Reading from file tmpfile_stdout 15:28:33 INFO - Current package versions: 15:28:33 INFO - argparse == 1.2.1 15:28:33 INFO - blessings == 1.6 15:28:33 INFO - blobuploader == 1.2.4 15:28:33 INFO - docopt == 0.6.1 15:28:33 INFO - functools32 == 3.2.3-2 15:28:33 INFO - jsonschema == 2.5.1 15:28:33 INFO - manifestparser == 1.1 15:28:33 INFO - mozInstall == 1.12 15:28:33 INFO - mozcrash == 0.17 15:28:33 INFO - mozdebug == 0.1 15:28:33 INFO - mozdevice == 0.48 15:28:33 INFO - mozfile == 1.2 15:28:33 INFO - mozhttpd == 0.7 15:28:33 INFO - mozinfo == 0.9 15:28:33 INFO - mozleak == 0.1 15:28:33 INFO - mozlog == 3.3 15:28:33 INFO - moznetwork == 0.27 15:28:33 INFO - mozprocess == 0.23 15:28:33 INFO - mozprofile == 0.28 15:28:33 INFO - mozrunner == 6.12 15:28:33 INFO - mozscreenshot == 0.1 15:28:33 INFO - mozsystemmonitor == 0.3 15:28:33 INFO - moztest == 0.7 15:28:33 INFO - mozversion == 1.4 15:28:33 INFO - psutil == 3.1.1 15:28:33 INFO - requests == 1.2.3 15:28:33 INFO - wsgiref == 0.1.2 15:28:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:33 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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28:33 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 15:28:33 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 15:28:33 INFO - Using env: {'DISPLAY': ':0', 15:28:33 INFO - 'HOME': '/home/cltbld', 15:28:33 INFO - 'LANG': 'en_US.UTF-8', 15:28:33 INFO - 'LANGUAGE': 'en_US:en', 15:28:33 INFO - 'LOGNAME': 'cltbld', 15:28:33 INFO - 'MAIL': '/var/mail/cltbld', 15:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:33 INFO - 'MOZ_NO_REMOTE': '1', 15:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:33 INFO - 'NO_EM_RESTART': '1', 15:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:33 INFO - 'PWD': '/builds/slave/test', 15:28:33 INFO - 'SHELL': '/bin/bash', 15:28:33 INFO - 'SHLVL': '1', 15:28:33 INFO - 'TERM': 'linux', 15:28:33 INFO - 'TMOUT': '86400', 15:28:33 INFO - 'USER': 'cltbld', 15:28:33 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:33 INFO - '_': '/tools/buildbot/bin/python'} 15:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 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)) 15:28:33 INFO - Cleaning up... 15:28:33 INFO - Return code: 0 15:28:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:33 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 0x199f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c60180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, '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': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', '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 15:28:33 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 15:28:33 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 15:28:33 INFO - Using env: {'DISPLAY': ':0', 15:28:33 INFO - 'HOME': '/home/cltbld', 15:28:33 INFO - 'LANG': 'en_US.UTF-8', 15:28:33 INFO - 'LANGUAGE': 'en_US:en', 15:28:33 INFO - 'LOGNAME': 'cltbld', 15:28:33 INFO - 'MAIL': '/var/mail/cltbld', 15:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:33 INFO - 'MOZ_NO_REMOTE': '1', 15:28:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:33 INFO - 'NO_EM_RESTART': '1', 15:28:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:33 INFO - 'PWD': '/builds/slave/test', 15:28:33 INFO - 'SHELL': '/bin/bash', 15:28:33 INFO - 'SHLVL': '1', 15:28:33 INFO - 'TERM': 'linux', 15:28:33 INFO - 'TMOUT': '86400', 15:28:33 INFO - 'USER': 'cltbld', 15:28:33 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:33 INFO - '_': '/tools/buildbot/bin/python'} 15:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:28:33 INFO - Cleaning up... 15:28:33 INFO - Return code: 0 15:28:33 INFO - Running post-action listener: _resource_record_post_action 15:28:33 INFO - Running post-action listener: _start_resource_monitoring 15:28:33 INFO - Starting resource monitoring. 15:28:33 INFO - [mozharness: 2016-08-08 22:28:33.675286Z] Finished create-virtualenv step (success) 15:28:33 INFO - [mozharness: 2016-08-08 22:28:33.675726Z] Running install step. 15:28:33 INFO - Running pre-action listener: _resource_record_pre_action 15:28:33 INFO - Running main action method: install 15:28:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:28:33 INFO - Reading from file tmpfile_stdout 15:28:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:28:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:28:33 INFO - Reading from file tmpfile_stdout 15:28:33 INFO - Output received: 15:28:33 INFO - Usage: mozinstall [options] installer 15:28:33 INFO - Options: 15:28:33 INFO - -h, --help show this help message and exit 15:28:33 INFO - -d DEST, --destination=DEST 15:28:33 INFO - Directory to install application into. [default: 15:28:33 INFO - "/builds/slave/test"] 15:28:33 INFO - --app=APP Application being installed. [default: firefox] 15:28:33 INFO - mkdir: /builds/slave/test/build/application 15:28:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:28:50 INFO - Reading from file tmpfile_stdout 15:28:50 INFO - Output received: 15:28:50 INFO - /builds/slave/test/build/application/firefox/firefox 15:28:50 INFO - Running post-action listener: _resource_record_post_action 15:28:50 INFO - [mozharness: 2016-08-08 22:28:50.187199Z] Finished install step (success) 15:28:50 INFO - [mozharness: 2016-08-08 22:28:50.187449Z] Running run-tests step. 15:28:50 INFO - Running pre-action listener: _resource_record_pre_action 15:28:50 INFO - Running main action method: run_tests 15:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:28:50 INFO - Python 2.7.3 15:28:50 INFO - Return code: 0 15:28:50 INFO - grabbing minidump binary from tooltool 15:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:50 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 0x1c5e3b0>, '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 0x1c5f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:28:50 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 15:28:50 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 15:28:50 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 15:28:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:28:50 INFO - Return code: 0 15:28:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:28:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:28:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:28:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:28:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:28:50 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/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 15:28:50 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/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 15:28:50 INFO - Using env: {'DISPLAY': ':0', 15:28:50 INFO - 'HOME': '/home/cltbld', 15:28:50 INFO - 'LANG': 'en_US.UTF-8', 15:28:50 INFO - 'LANGUAGE': 'en_US:en', 15:28:50 INFO - 'LOGNAME': 'cltbld', 15:28:50 INFO - 'MAIL': '/var/mail/cltbld', 15:28:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:28:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:50 INFO - 'MOZ_NO_REMOTE': '1', 15:28:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:28:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:28:50 INFO - 'NO_EM_RESTART': '1', 15:28:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:50 INFO - 'PWD': '/builds/slave/test', 15:28:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:28:50 INFO - 'SHELL': '/bin/bash', 15:28:50 INFO - 'SHLVL': '1', 15:28:50 INFO - 'TERM': 'linux', 15:28:50 INFO - 'TMOUT': '86400', 15:28:50 INFO - 'USER': 'cltbld', 15:28:50 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321', 15:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:50 INFO - '_': '/tools/buildbot/bin/python'} 15:28:50 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/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 15:28:50 INFO - mozversion application_buildid: 20160808143420 15:28:50 INFO - mozversion application_changeset: a8df38f8ada3aca17303f21b9d447dafd7bbb9d6 15:28:50 INFO - mozversion application_display_name: Nightly 15:28:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:28:50 INFO - mozversion application_name: Firefox 15:28:50 INFO - mozversion application_remotingname: firefox 15:28:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:28:50 INFO - mozversion application_vendor: Mozilla 15:28:50 INFO - mozversion application_version: 51.0a1 15:28:50 INFO - mozversion platform_buildid: 20160808143420 15:28:50 INFO - mozversion platform_changeset: a8df38f8ada3aca17303f21b9d447dafd7bbb9d6 15:28:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:28:50 INFO - mozversion platform_version: 51.0a1 15:28:50 INFO - using testdate: 1470695330 15:28:50 INFO - actual date: 1470695330 15:28:50 INFO - starting webserver on 'localhost:36593' 15:28:50 INFO - SUITE-START | Running 2 tests 15:28:50 INFO - TEST-START | dromaeo_css 15:28:50 INFO - operating with platform_type : linux_ 15:28:50 INFO - Initialising browser for dromaeo_css test... 15:28:50 INFO - TEST-INFO | started process 10550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ2IdkJ/profile http://localhost:36593/getInfo.html) 15:28:55 INFO - PROCESS | 10550 | __metrics Screen width/height:1600/1200 15:28:55 INFO - PROCESS | 10550 | colorDepth:24 15:28:55 INFO - PROCESS | 10550 | Browser inner width/height: 1024/697 15:28:55 INFO - PROCESS | 10550 | __metrics 15:28:55 INFO - PROCESS | 10550 | 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:28:55 INFO - PROCESS | 10550 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:28:56 INFO - TEST-INFO | 10550: exit 0 15:28:56 INFO - Browser initialized. 15:28:56 INFO - Running cycle 1/1 for dromaeo_css test... 15:28:56 INFO - TEST-INFO | started process 10632 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ2IdkJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:08 INFO - PROCESS | 10632 | RSS: Main: 157417472 15:29:08 INFO - PROCESS | 10632 | 15:29:59 INFO - PROCESS | 10632 | 1470695399726 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] 15:32:12 INFO - PROCESS | 10632 | Cycle 1(1): loaded http://localhost:36593/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36593/tests/dromaeo/cssquery-ext.html) 15:32:13 INFO - PROCESS | 10632 | RSS: Main: 244281344 15:32:13 INFO - PROCESS | 10632 | 15:35:16 INFO - PROCESS | 10632 | Cycle 1(1): loaded http://localhost:36593/tests/dromaeo/cssquery-ext.html (next: http://localhost:36593/tests/dromaeo/cssquery-jquery.html) 15:35:17 INFO - PROCESS | 10632 | RSS: Main: 241487872 15:35:17 INFO - PROCESS | 10632 | 15:38:19 INFO - PROCESS | 10632 | Cycle 1(1): loaded http://localhost:36593/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36593/tests/dromaeo/cssquery-mootools.html) 15:38:20 INFO - PROCESS | 10632 | RSS: Main: 215867392 15:38:20 INFO - PROCESS | 10632 | 15:41:22 INFO - PROCESS | 10632 | Cycle 1(1): loaded http://localhost:36593/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36593/tests/dromaeo/cssquery-prototype.html) 15:41:22 INFO - PROCESS | 10632 | RSS: Main: 189648896 15:41:22 INFO - PROCESS | 10632 | 15:44:25 INFO - PROCESS | 10632 | Cycle 1(1): loaded http://localhost:36593/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36593/tests/dromaeo/cssquery-yui.html) 15:44:26 INFO - PROCESS | 10632 | RSS: Main: 189849600 15:44:26 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | Cycle 1(1): loaded http://localhost:36593/tests/dromaeo/cssquery-yui.html (next: http://localhost:36593/tests/dromaeo/cssquery-dojo.html) 15:47:29 INFO - PROCESS | 10632 | RSS: Main: 281124864 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | __start_tp_report 15:47:29 INFO - PROCESS | 10632 | _x_x_mozilla_page_load 15:47:29 INFO - PROCESS | 10632 | _x_x_mozilla_page_load_details 15:47:29 INFO - PROCESS | 10632 | |i|pagename|runs| 15:47:29 INFO - PROCESS | 10632 | |0;dojo.html;2634.657494525714;2647.3911000469893;2673.692525359583;2680.3031211884904;2691.1522870295858;4556.020455602045;4620.97689511552;4718.764061796909;4870.391201099863;4887.7311747853855;4767.451743049551;4775.976120119403;4814.518548145193;4849.563539281464;4860.902781944359;4336.609705126538;4341.50072741634;4429.800658970338;4442.155990361821;4517.932231016521;4450.554944505556;4494.505604383515;4511.887202819923;4531.818727250906;4533.8413155539565;4288.270993931039;4333.674974376934;4336.522982471925;4360.65114790816;4445.377647129404;4378.365137055141;4384.342348647697;4403.933940990888;4491.6855820092605;4500.3249512573375;4415.889602759957;4420.248557745191;4434.312681534368;4464.6874718769695;4467.173572889026;4641.605463535603;4826.444958829745;4833.7583120844165;4839.516048395168;4890.877728056827;5056;5137.871553211162;5140.794368225266;5183.559397451221;5219;5212.40057393397;5235;5241.842744717665;5248.763805628756;5249.265102885599;4659.790309436084;4894.26586012094;4897.902041959141;4902.093112774148;4902.1176188285735;5227.634065615371;5306.734663266821;5307.655002424907;5388.057090009311;5405.351357837085;5084.186530155157;5107.489251074937;5141.897162056738;5153.613478989091;5167.741612919339;4974.726390048582;5227.477252274818;5238.659487133325;5245.763940622604;5247.055530004636;5051.1413059779925;5060.7722652480725;5114.130597798383;5192.818251361185;5194.35070616173;5119.667221630582;5124.128898087334;5135.6148288878485;5138.152204886227;5176.689398636094;4430.158269928703;4432.645388368923;4433.91369114569;4473.239549276631;4481.686281960231;3763.2473505298503;3780.281746468162;3807.447920051577;3859.4789703390243;3876.7480113792512;4435.179491794029;4436.556344365537;4456.17560751262;4473.619742321939;4480.910381792346;5200.375954885438;5219.060569097523;5226.529612334908;5226.790928362823;5275.366955965232;4084.6936479764136;4212.515560710496;4326.307790753464;4427.8228870844805;4440.4227450431235;4639.257718764981;4663.020765639186;4666.090112428112;4694.577488026094;4706.576408123286;4854.393200849894;4857.174280372275;4858.028394321221;4862.625598741262;4888.364512613338;4974.228994505857;4974.726390048438;4997.700137991732;5162.432132465501;5197.974010129925;4709.5055019223855;4727.4799772025735;4730.385049943486;4765.9046819064115;4772.427308722976;4953.678010929278;4965.1311020571975;4973.104841128632;4975.477574854588;5009.9248511271635;4968.4286307075145;5040.647154699136;5082.669626474268;5091.6181286402925;5168.121419358643;5039.168537191323;5042.823501177441;5047.318611987482;5070.442251352422;5076.7207803571155;5036.974815125901;5052.418971818288;5054.064997975239;5061.164907790173;5105.31078304439;4731.526847315241;4900.705957642553;4944.381952255968;4965.304857319906;4966.155753521982;5099.209622508517;5101.107306221322;5101.9489805101475;5140.254663073759;5157.200633901752;4979.526944940225;4981.252812078073;4981.850544483671;4994.625403094855;5040.924386134137 15:47:29 INFO - PROCESS | 10632 | |1;ext.html;547.7535109200859;564.7825587148947;565.8189379398591;572.6764378126359;572.7193675099203;28547;41380.37929431061;42755.78622106889;42859.78570107153;42869.7856510717;39364.80317598408;39828.40257396134;39873.60126398735;39916.21005479347;39931.80034099833;7588.127365352972;7714.382849372037;7722.227777222289;7723.034620672416;7724.111727151393;7375.151857536398;9786.90213097867;10024.548895299693;10043;10330.483475826217;9358;9431.952840235823;9440.480773557452;9445.71662850112;9459.57431915562;9913.504324783768;9922.404655720607;9978.999999999964;9982.600695972225;9985.151262142726;9868.358556693864;9936.552855121538;9939.453330066843;9940.304178707493;9945.055219754111;38168.42747358764;38585.80707096447;38652.420213696816;38703.22593548113;38703.22593548113;3048.695130486956;3126.5622812806228;3425.640307767673;3478.217401084761;3805.1818858945185;27350.589741153897;28190.295242618893;29311.608473891745;29863.25341866449;29999.700002999907;3521.7710848795004;3556.395412779834;3612.801295928723;3636.5272514572935;3757.511523501955;258356;261724;261893;263576;264119.9999999981;56276.718616407474;56910.430895690515;57031.42968570261;57155;57221;33282.16794580106;34383.31233375319;34384.31231375408;36382.454263186075;36413.63586364155;13856.099353541988;13864.930675346559;13971.301434928213;13976.161430314214;13985.510507132201;9895.505224738734;9911.900880991241;9932;10093.596256149818;10365.429901355346;16978.915105424392;16990.235439405256;17117.400890969006;19491;19670.311539096063;7196.028536147658;7320.96339518299;7330.8900366494545;7414.814629634302;7470.4770666052855;765.4106338119617;869.6130222051127;911.1708345405649;912.9224015958586;944.1125342178331;27095.322616934733;27543.17370478889;27769.305767355978;27886.3028424291;28069;856.6273670953155;867.1458613265928;916.4821875640375;917.6512925088557;938.648006997376;14133.434662613587;14515;14588.85411145875;14707.191104489355;14861.851381486047;408575;413216;413474;413657;414022;49291.753541232065;49855.750721246884;49971.50028499741;50007.74996124996;50061.74969125204;1125.2573301621055;1158.5250047480492;1176.4294317256295;1180.3331117918349;1181.2144923625865;25806.483870322114;26956.191314260603;27019;27420.45159096767;27533.449331013668;43410.78294608507;43516.129677406905;43631.78184109059;43633.345499816896;43652.12695746131;340708;341367;342214;343140;343218;22835.543289134457;23496.647550286416;24236.51526969486;25931.22206333813;26324.4735105293;37966.24067518689;40322.59677403188;40438.79780601078;40674;41029;35335.64664353323;35471.29057418889;35724.46413303854;35772.821135894155;36349.636503634625;35506.289874202885;35953.64046359608;35979.761821436194;36041.27917441584;36047;34035.14912127118;34094.65905340915;34593.827030865694;34781.304373912884;34814.65185348114;29230.853845731486;34069.829650851585;34161.75463330979;34205.65794342124;34327.65672343245;24749.505009900062;25777.226683199533;26650;26880.462390751683;27347.179584612495 15:47:29 INFO - PROCESS | 10632 | |2;jquery.html;338.8898607952416;357.9767315124517;366.27477594363154;367.35529146348165;378.5835580861053;12524.18592791469;15058.548243552697;16582.83417165828;16859.072750998705;17511.912440437813;14307.427702891871;14777.408903643842;14818.703625927476;14857.479988200388;15421;4616.238320677093;4903.82836600719;5151.227315902607;5177.042247184264;5181.4300426953005;3482.320947415255;5086.059079070365;5169.3279873616375;5184.274201611777;5664.178694089354;5944.197533332988;5967.134765459005;5978.551608629348;5983.491403230731;6031.849203769897;5302.628815982883;5393.919091213635;5437.510624043855;5877.500412464967;5977.043673012319;5266.183741520071;5273.2617433559335;5275.551578115865;5278.208268759717;5278.894422111574;12626.55807046749;14937.477188298357;20884.164633414795;21085;21129.788702112935;1856.6843636581814;1869.2242719271485;1994.450483721257;2007.7289565908561;2229.27548546723;9012;9485.573149208301;9691.951540242324;9826.90173098267;9869.407835529892;1754;1758.7449819776061;1801.711726123827;1814.637072585488;1816.7184086466618;68338.31661683369;69604.30395696028;70855.64572177158;72351.63824180845;72826.27173728248;10240.436775977392;10253.128484078788;10424.947875260727;10520.158387328995;10551.419671917965;14018.579442616738;14023.439062437388;14028.368723407471;14048.85951140496;14052.508162214273;7243;7259.419246460376;7265;7320.853582928312;7517.473776835678;6824.727010919508;6834.2482326944;6856.348646878639;6985.371316581431;7056.2359410147465;3936.4292177634084;4187.664986801049;4257.063446041866;4389.144116897174;4529.9547004530195;1805.8826176298762;1840.4386662068198;1855.7587513623412;1857.3220774417512;1907.8282954534159;725.6263024542365;822.8971378577678;827.2058823529479;914.3325372478129;921.6497730862363;16232.75650865238;16949.32202711878;17202.569935751708;17849.37527186542;17989.910050449664;848.6902280667512;854.9786255343541;865.16079402979;877.5612193903048;920.447731361178;9519.571619277149;9523.857142142873;9830.115289623967;9870.457124858058;9891.455969921722;406538;407872;417176;419758;420104;76233;76649;77077;78927;84108;794.3684770607266;797.2704974947974;799.580220384303;799.8280369720538;909.2135302963021;15347.232638367814;16165.27256273447;16242.593935151715;16462.75305870437;16512.66974660524;29716.55425168581;39000.41499377568;39139.804300978314;39249.411258831715;39564.80217598894;54027.459725402245;54440;56875.14687279611;57768;58005.4199458;6326.746930122744;6513.8697226056165;6563.704633291418;6787.932120678927;6818.8636227276165;8184.468009579358;9871.950640246752;10882.891171088504;11212.439378030902;11428.771424571629;9328.440293582407;9425.952870235878;9451.102145296185;9579.137877590772;9853.901460985298;9763.170130538983;9908.801823963337;10033.949830250802;10091.848622270525;10192.949035254776;9456.338056336266;9519.57161927701;9614.278929080487;9747.951260243653;10188.490575471049;6609.570377925419;6651.534392592472;6739.59562426256;6748.223954245128;6964.303569642995;8839.867401989104;9401.10689484498;9659.372140810825;9684.46735429558;9850.16273616722 15:47:29 INFO - PROCESS | 10632 | |3;mootools.html;1212.4786341872996;1213.1750409721396;1277.3357853915963;1368.9452421903125;1378.221304962696;6208.255009398873;7510.361619262362;7735.806604834875;7823.608819559027;8028.35773138149;7844.215578442153;7920.009998750156;7920.524768513878;7923.48497347672;7929.365650747941;2096.3082182879652;2105.1789801977243;2107.915683372671;2108.325335892515;2111.556573119639;2117.745870495535;2147.8281737460966;2174.4346469917778;2177.4556360909774;2177.68423578581;2132.722746043012;2152.1929276521305;2161.8702877827304;2163.751168615606;2164.2425151197112;2132.1364847236896;2137.1558234497443;2139.0801955159277;2140.122549754609;2140.83943704222;2059.794020597943;2118.0998075817715;2118.533922537055;2119.7562280337806;2121.586290673319;7794.9220507795335;7942.245486678814;7944.682212711485;7944.801379965489;7947.08608510023;4245.936310955338;4419.204543182226;4422.115576884636;4447.466304043503;4457.509673935864;2718.497078040569;2862.9856850715823;2932.2669332666833;2935.500964835983;2936.368680733631;872.6596627315353;885.7077164535688;889.2707979456848;898.5192921786812;901.2519608724742;10785.406802625852;10842.783144337149;10871.67384978452;10871.891281087166;10876.619318323821;3701.61133081028;3789.242151569697;3996.8401263949117;4000.3599424092586;4027.456293400412;8506.234438900528;8532.786680333042;8532.914670853335;8540.74377768668;8546.444481108709;4367.388565600819;4393.165298593321;4395.8021889014435;4398.098389830077;4398.142362239332;3382.086836554166;3865.1110244643887;3999.7800120993043;4080.9387859182134;4127.938080928789;3080.9383812323626;3106.08370530694;3188.457962146441;3193.4890417533084;3204.279037216608;994.3636072913341;1001.5993602559034;1004.1615251265275;1006.8036732837027;1045.0698877998586;595.2499850188793;604.9909251361233;702.78565037664;718.6730037832774;721.1274358026847;4795.256735206049;4854.80580776765;5009.148444764399;5131.846044618667;5177.663451875616;779.7582749347607;791.2206476620524;821.2075347289893;853.7918844834613;887.7070566712969;3216.453202955503;3403.9149021274666;3429.3998550253605;3478.130467383154;3572.517710109154;9318;9617;9826.115649591571;9845.655402060893;9853.556589953469;9869.21046316293;9879.407235565888;9884.456354900405;9904.7028589141;9967.850482242773;7373.8156546086775;7376.594287314249;7430.554166750012;7517.285857843496;7522.059742532184;5085.211792172219;5131.56381707559;5192.1952097424955;5234.476552344735;5240.423553409199;8868.15752503511;8868.423552469068;9093.954530227307;9110.908890911007;9245.214156796748;9069.77325566866;9207.539623018956;9280.535973201448;9341.813163736822;9348.719538413858;5497.5601951842955;6228.377162283917;6312;6318.494520438262;6319.3364696706185;6287.937120628918;6429.164208652845;6831.385175334244;6973.895391569232;7007.089078419773;5968.373320801254;6238.844028899314;6263.185785847811;6392.328805475358;6515.413612774873;6172.567920245539;6348.523860710373;6356.173697419491;6361.363863613602;6373.840653983688;5659.6321239119325;5672.319321681424;5698.629589076862;5709.743061562157;5736.19693242938;4272.252355837778;4329.956700432956;4388.868333949987;4394.077243778715;4519.367288579536;4396.252637051773;4413.602775750198;4448.154850578379;4505.864824055284;4522.954770452254 15:47:29 INFO - PROCESS | 10632 | |4;prototype.html;275.3982548132331;287.44666516954857;290.3756922616376;291.93577412969165;293.3897493214115;4942.357493525846;4950.207966725325;4952.603791696664;4961.751912404384;4967.304577359173;4819.590334821544;4829.299751536025;4849.321095046696;4886.218205087187;4908.582770464514;3579.284143171363;3699.500567423404;3707.3697471429923;3792.5448946126503;3819.6562309392148;3815.561210460791;3818.198178382529;3822.7132965027595;3839.9232015359817;3850.84596616135;3782.7162962777766;3804.828782704771;3812.294725475796;3814.256220037094;3824.7131465140087;3621.9818900905457;3643.544556930384;3712.424574191005;3759.755615884959;3816.6755825754844;3265.42855000374;3268.3790079884748;3274.4106060909025;3274.5743053403144;3277.2953814929665;4704.270838020078;4718.41019872516;4734.958309171977;4754.144254034272;4780.80876764929;4083.6528895043957;4238.300680387732;4261.019965407942;4268.701190916637;4293.420388247578;5007.12375334315;5020;5020.221865610836;5025.949740502621;5067.137067556819;3593.5328407306883;3604.585472670624;3607.5670919489567;3609.043603445063;3610.259896721192;5123.666961647481;5291.3121294231505;5291.973540132313;5307.840764777063;5333.759980800874;3614.331348700499;3620.475031120473;3627.72792040598;3628.1473853644375;3633.94549081764;3678.448232765053;3705.5738590061947;3709.8145092745253;3710.9814450927574;3717.0893131182493;4113.650339721097;4246.723962942398;4266.914661706748;4275.786210689453;4282.978585107055;4967.677100988424;4975.751212439363;5033.723145227048;5049.368828896388;5081.695098294114;1249.3565813606;1271.955481558141;1272.1349482351904;1281.6475469245845;1282.9928506122649;754.8339365339616;788.6096382290802;795.1055063054064;795.777182388932;798.66456088443;4325.567443255649;4435.955640443619;4459.821607135743;4464.866054018385;4489.528599497071;5189.662671926313;5191.870203244949;5201.517567493246;5204.453532379046;5210.583153347723;4435.578620031092;4443;4445.888852778642;4445.977770111193;4458.770614692654;2592.949855308603;2759.4619049285598;2760.2961244882426;2778.5554311310498;2781.694013658496;4882.584980276645;4904.975475122602;4924.507549245047;4947.752612369367;4959.752012399365;5234.602282616789;5247.685138891678;5302.946970530245;5350.732463376816;5358.758855851496;4794.95205047952;4876;4882.609391248691;4903.411590609078;4929.309896614405;5263.289455923401;5267.5522580579545;5272.2882410874045;5299;5337.332833395825;4860.489648586848;5107.79568817258;5148.613853960893;5180.171172612515;5199.142141546754;4929.088118698052;4936.086823937583;4940.456549779449;4951.801927922843;4959.504049595012;3645.505342809435;3649.105969037603;3696.5564132304294;3710.6103859094405;3721.7580857244193;5109.1059064662795;5217.34783152106;5313.9468605315;5325.360956685223;5330.920036199383;5007.899842003067;5008.472415913181;5041.974790126026;5051.595872330278;5060.493950605058;5046.823361182341;5050.636328191441;5063.87340316495;5082.3138876252715;5133.332666753298;4742.766880610997;4980.431164183271;4984.526469985346;4989.575886049582;4993.875153121201;4857.95142048575;4946.431160416598;5086.796528138981;5113.53978141969;5114.667546609458;4947.6536642434685;4966.205407134986;4983.875403114806;5004.974975125101;5013.699178049183 15:47:29 INFO - PROCESS | 10632 | |5;yui.html;614.8370681769331;622.3247776162863;644.9580777249479;652.6964961292999;694.0942070597868;13120.21278723276;13561.389737461814;13585.252811095386;13701.65745856354;13958.790618140736;14200.715985680281;14918.925405372958;15007.924960375183;15026.248687565605;15026.624334391618;757.1330826203979;780.0872978615006;782.7377828427484;785.2579312549643;786.2334224131455;630.124127462827;743.7396911081105;759.840433508961;760.2473551184343;773.957432341221;800.0639252075057;803.3211935914143;804.5454318310166;804.8752443371258;805.9032916050082;799.580220384297;800.8478389106081;801.1107670485757;801.3949468151508;801.5631480842954;476.0384024270988;572.2732130194629;580.5413723158741;583.8627922438224;594.4204400709317;13839.723205535933;13855.930720346334;13856;13857.4457021719;13860.792088118686;12093.334866582334;12517.624471265786;12669.873301266962;12889.935550322189;13423;11870.762584748256;11972.401379930969;12022.819657705142;12197.024238060934;12862.421191046426;4091.2226676931286;4133.1940271647045;4134.9118695059105;4281.422008028907;4288.120935208275;54609.72695136539;56915.14627280594;56971.71514142403;62125.37874621241;63095.36904630941;10297.9485102574;10787.6224332148;10853.728656783644;10874.02133807961;10895.346279223271;18929.621407571773;18929.716054259196;18936.053197340352;18941.33705320307;18962.051897405076;6803.183617965777;6876.621785801829;6883.45691843391;7007.4394048476;7267.491275610762;3757.6993840492696;3764.378877485184;3771.07608635886;3834.4631751554803;3851.557070936822;2891.9855400722863;3010.969890301069;3013.231625935416;3025.4554180247765;3026.8789248429816;499.45169973083154;502.5979216626684;503.67764630636424;517.6195496310208;519.3378442485861;463.73798803675817;524.3419508516863;540.8350453111839;543.7824870051932;546.3580293155605;7872.488288261245;7985.361171106298;8035.879461808077;8181.509109453452;8355.206255405848;429.84310726584573;444.4999375702335;455.4693781744297;464.37773383665376;550.1829782772579;3859.4789703390243;3953.1303113315025;3972.56301806801;4021.8190181441905;4136.896577585524;53156.46843531515;55275.44724552784;55638.44361556332;55702.442975570535;55985.720071400196;81470;82158.58920705358;82636.17363826405;82953.58523207346;84082.15917840866;73247;73362;76139.23860761544;76295;77753.99999999886;7434.2565743427385;7613.428992825449;7758.146603873683;7803.219678032151;7810.567762487528;44424.33363499485;44557.55442445534;44998.77500612476;46311.305330419396;46643.53356466392;57456.1381579285;66684.66657666875;67444.66277668776;68641.65679171572;71362.64318678374;9972;10031.598736050477;10049.497525123861;10159.136473399843;10411.635592754217;11280.661580152608;11333.433328333385;11349.432528373383;11388.544458221579;12049.397530123633;9860.408375497493;10322.174226062047;10702.304350217522;10871.130309575374;10953.34279943206;9761.951190244003;10471.952804719467;11157.66527004191;11311.095112391153;11348.659540213806;11647.59233426826;11815.409229538316;11949.402529873298;12290.262584244974;12395.93802030984;9809.068138526829;9903.158231550111;10187.847182292124;10466.319689220176;10869.347839129678;8944.329175312008;9645.228381729305;9757.756056098655;9814.312998090065;9828.4102953823 15:47:29 INFO - PROCESS | 10632 | __end_tp_report 15:47:29 INFO - PROCESS | 10632 | __start_cc_report 15:47:29 INFO - PROCESS | 10632 | _x_x_mozilla_cycle_collect,950 15:47:29 INFO - PROCESS | 10632 | __end_cc_report 15:47:29 INFO - PROCESS | 10632 | __startTimestamp1470696449924__endTimestamp 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | ------- Summary: start ------- 15:47:29 INFO - PROCESS | 10632 | Number of tests: 6 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | [#0] dojo.html Cycles:165 Average:4759.85 Median:4894.27 stddev:509.67 (10.4%) stddev-sans-first:483.20 15:47:29 INFO - PROCESS | 10632 | Values: 2634.7 2647.4 2673.7 2680.3 2691.2 4556.0 4621.0 4718.8 4870.4 4887.7 4767.5 4776.0 4814.5 4849.6 4860.9 4336.6 4341.5 4429.8 4442.2 4517.9 4450.6 4494.5 4511.9 4531.8 4533.8 4288.3 4333.7 4336.5 4360.7 4445.4 4378.4 4384.3 4403.9 4491.7 4500.3 4415.9 4420.2 4434.3 4464.7 4467.2 4641.6 4826.4 4833.8 4839.5 4890.9 5056.0 5137.9 5140.8 5183.6 5219.0 5212.4 5235.0 5241.8 5248.8 5249.3 4659.8 4894.3 4897.9 4902.1 4902.1 5227.6 5306.7 5307.7 5388.1 5405.4 5084.2 5107.5 5141.9 5153.6 5167.7 4974.7 5227.5 5238.7 5245.8 5247.1 5051.1 5060.8 5114.1 5192.8 5194.4 5119.7 5124.1 5135.6 5138.2 5176.7 4430.2 4432.6 4433.9 4473.2 4481.7 3763.2 3780.3 3807.4 3859.5 3876.7 4435.2 4436.6 4456.2 4473.6 4480.9 5200.4 5219.1 5226.5 5226.8 5275.4 4084.7 4212.5 4326.3 4427.8 4440.4 4639.3 4663.0 4666.1 4694.6 4706.6 4854.4 4857.2 4858.0 4862.6 4888.4 4974.2 4974.7 4997.7 5162.4 5198.0 4709.5 4727.5 4730.4 4765.9 4772.4 4953.7 4965.1 4973.1 4975.5 5009.9 4968.4 5040.6 5082.7 5091.6 5168.1 5039.2 5042.8 5047.3 5070.4 5076.7 5037.0 5052.4 5054.1 5061.2 5105.3 4731.5 4900.7 4944.4 4965.3 4966.2 5099.2 5101.1 5101.9 5140.3 5157.2 4979.5 4981.3 4981.9 4994.6 5040.9 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | [#1] ext.html Cycles:180 Average:48742.11 Median:19993.08 stddev:90828.56 (454.3%) stddev-sans-first:91011.28 15:47:29 INFO - PROCESS | 10632 | Values: 547.8 564.8 565.8 572.7 572.7 28547.0 41380.4 42755.8 42859.8 42869.8 39364.8 39828.4 39873.6 39916.2 39931.8 7588.1 7714.4 7722.2 7723.0 7724.1 7375.2 9786.9 10024.5 10043.0 10330.5 9358.0 9432.0 9440.5 9445.7 9459.6 9913.5 9922.4 9979.0 9982.6 9985.2 9868.4 9936.6 9939.5 9940.3 9945.1 38168.4 38585.8 38652.4 38703.2 38703.2 3048.7 3126.6 3425.6 3478.2 3805.2 27350.6 28190.3 29311.6 29863.3 29999.7 3521.8 3556.4 3612.8 3636.5 3757.5 258356.0 261724.0 261893.0 263576.0 264120.0 56276.7 56910.4 57031.4 57155.0 57221.0 33282.2 34383.3 34384.3 36382.5 36413.6 13856.1 13864.9 13971.3 13976.2 13985.5 9895.5 9911.9 9932.0 10093.6 10365.4 16978.9 16990.2 17117.4 19491.0 19670.3 7196.0 7321.0 7330.9 7414.8 7470.5 765.4 869.6 911.2 912.9 944.1 27095.3 27543.2 27769.3 27886.3 28069.0 856.6 867.1 916.5 917.7 938.6 14133.4 14515.0 14588.9 14707.2 14861.9 408575.0 413216.0 413474.0 413657.0 414022.0 49291.8 49855.8 49971.5 50007.7 50061.7 1125.3 1158.5 1176.4 1180.3 1181.2 25806.5 26956.2 27019.0 27420.5 27533.4 43410.8 43516.1 43631.8 43633.3 43652.1 340708.0 341367.0 342214.0 343140.0 343218.0 22835.5 23496.6 24236.5 25931.2 26324.5 37966.2 40322.6 40438.8 40674.0 41029.0 35335.6 35471.3 35724.5 35772.8 36349.6 35506.3 35953.6 35979.8 36041.3 36047.0 34035.1 34094.7 34593.8 34781.3 34814.7 29230.9 34069.8 34161.8 34205.7 34327.7 24749.5 25777.2 26650.0 26880.5 27347.2 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | [#2] jquery.html Cycles:180 Average:24978.46 Median:5290.76 stddev:68441.20 (1293.6%) stddev-sans-first:68608.19 15:47:29 INFO - PROCESS | 10632 | Values: 338.9 358.0 366.3 367.4 378.6 12524.2 15058.5 16582.8 16859.1 17511.9 14307.4 14777.4 14818.7 14857.5 15421.0 4616.2 4903.8 5151.2 5177.0 5181.4 3482.3 5086.1 5169.3 5184.3 5664.2 5944.2 5967.1 5978.6 5983.5 6031.8 5302.6 5393.9 5437.5 5877.5 5977.0 5266.2 5273.3 5275.6 5278.2 5278.9 12626.6 14937.5 20884.2 21085.0 21129.8 1856.7 1869.2 1994.5 2007.7 2229.3 9012.0 9485.6 9692.0 9826.9 9869.4 1754.0 1758.7 1801.7 1814.6 1816.7 68338.3 69604.3 70855.6 72351.6 72826.3 10240.4 10253.1 10424.9 10520.2 10551.4 14018.6 14023.4 14028.4 14048.9 14052.5 7243.0 7259.4 7265.0 7320.9 7517.5 6824.7 6834.2 6856.3 6985.4 7056.2 3936.4 4187.7 4257.1 4389.1 4530.0 1805.9 1840.4 1855.8 1857.3 1907.8 725.6 822.9 827.2 914.3 921.6 16232.8 16949.3 17202.6 17849.4 17989.9 848.7 855.0 865.2 877.6 920.4 9519.6 9523.9 9830.1 9870.5 9891.5 406538.0 407872.0 417176.0 419758.0 420104.0 76233.0 76649.0 77077.0 78927.0 84108.0 794.4 797.3 799.6 799.8 909.2 15347.2 16165.3 16242.6 16462.8 16512.7 29716.6 39000.4 39139.8 39249.4 39564.8 54027.5 54440.0 56875.1 57768.0 58005.4 6326.7 6513.9 6563.7 6787.9 6818.9 8184.5 9872.0 10882.9 11212.4 11428.8 9328.4 9426.0 9451.1 9579.1 9853.9 9763.2 9908.8 10033.9 10091.8 10192.9 9456.3 9519.6 9614.3 9748.0 10188.5 6609.6 6651.5 6739.6 6748.2 6964.3 8839.9 9401.1 9659.4 9684.5 9850.2 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | [#3] mootools.html Cycles:180 Average:4986.62 Median:5184.93 stddev:2910.01 (56.1%) stddev-sans-first:2904.35 15:47:29 INFO - PROCESS | 10632 | Values: 1212.5 1213.2 1277.3 1368.9 1378.2 6208.3 7510.4 7735.8 7823.6 8028.4 7844.2 7920.0 7920.5 7923.5 7929.4 2096.3 2105.2 2107.9 2108.3 2111.6 2117.7 2147.8 2174.4 2177.5 2177.7 2132.7 2152.2 2161.9 2163.8 2164.2 2132.1 2137.2 2139.1 2140.1 2140.8 2059.8 2118.1 2118.5 2119.8 2121.6 7794.9 7942.2 7944.7 7944.8 7947.1 4245.9 4419.2 4422.1 4447.5 4457.5 2718.5 2863.0 2932.3 2935.5 2936.4 872.7 885.7 889.3 898.5 901.3 10785.4 10842.8 10871.7 10871.9 10876.6 3701.6 3789.2 3996.8 4000.4 4027.5 8506.2 8532.8 8532.9 8540.7 8546.4 4367.4 4393.2 4395.8 4398.1 4398.1 3382.1 3865.1 3999.8 4080.9 4127.9 3080.9 3106.1 3188.5 3193.5 3204.3 994.4 1001.6 1004.2 1006.8 1045.1 595.2 605.0 702.8 718.7 721.1 4795.3 4854.8 5009.1 5131.8 5177.7 779.8 791.2 821.2 853.8 887.7 3216.5 3403.9 3429.4 3478.1 3572.5 9318.0 9617.0 9826.1 9845.7 9853.6 9869.2 9879.4 9884.5 9904.7 9967.9 7373.8 7376.6 7430.6 7517.3 7522.1 5085.2 5131.6 5192.2 5234.5 5240.4 8868.2 8868.4 9094.0 9110.9 9245.2 9069.8 9207.5 9280.5 9341.8 9348.7 5497.6 6228.4 6312.0 6318.5 6319.3 6287.9 6429.2 6831.4 6973.9 7007.1 5968.4 6238.8 6263.2 6392.3 6515.4 6172.6 6348.5 6356.2 6361.4 6373.8 5659.6 5672.3 5698.6 5709.7 5736.2 4272.3 4330.0 4388.9 4394.1 4519.4 4396.3 4413.6 4448.2 4505.9 4523.0 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | [#4] prototype.html Cycles:180 Average:4200.07 Median:4859.22 stddev:1235.69 (25.4%) stddev-sans-first:1203.54 15:47:29 INFO - PROCESS | 10632 | Values: 275.4 287.4 290.4 291.9 293.4 4942.4 4950.2 4952.6 4961.8 4967.3 4819.6 4829.3 4849.3 4886.2 4908.6 3579.3 3699.5 3707.4 3792.5 3819.7 3815.6 3818.2 3822.7 3839.9 3850.8 3782.7 3804.8 3812.3 3814.3 3824.7 3622.0 3643.5 3712.4 3759.8 3816.7 3265.4 3268.4 3274.4 3274.6 3277.3 4704.3 4718.4 4735.0 4754.1 4780.8 4083.7 4238.3 4261.0 4268.7 4293.4 5007.1 5020.0 5020.2 5025.9 5067.1 3593.5 3604.6 3607.6 3609.0 3610.3 5123.7 5291.3 5292.0 5307.8 5333.8 3614.3 3620.5 3627.7 3628.1 3633.9 3678.4 3705.6 3709.8 3711.0 3717.1 4113.7 4246.7 4266.9 4275.8 4283.0 4967.7 4975.8 5033.7 5049.4 5081.7 1249.4 1272.0 1272.1 1281.6 1283.0 754.8 788.6 795.1 795.8 798.7 4325.6 4436.0 4459.8 4464.9 4489.5 5189.7 5191.9 5201.5 5204.5 5210.6 4435.6 4443.0 4445.9 4446.0 4458.8 2592.9 2759.5 2760.3 2778.6 2781.7 4882.6 4905.0 4924.5 4947.8 4959.8 5234.6 5247.7 5302.9 5350.7 5358.8 4795.0 4876.0 4882.6 4903.4 4929.3 5263.3 5267.6 5272.3 5299.0 5337.3 4860.5 5107.8 5148.6 5180.2 5199.1 4929.1 4936.1 4940.5 4951.8 4959.5 3645.5 3649.1 3696.6 3710.6 3721.8 5109.1 5217.3 5313.9 5325.4 5330.9 5007.9 5008.5 5042.0 5051.6 5060.5 5046.8 5050.6 5063.9 5082.3 5133.3 4742.8 4980.4 4984.5 4989.6 4993.9 4858.0 4946.4 5086.8 5113.5 5114.7 4947.7 4966.2 4983.9 5005.0 5013.7 15:47:29 INFO - PROCESS | 10632 | 15:47:29 INFO - PROCESS | 10632 | [#5] yui.html Cycles:180 Average:16634.15 Median:487.75 stddev:22398.24 (4592.2%) stddev-sans-first:22428.77 15:47:29 INFO - PROCESS | 10632 | Values: 614.8 622.3 645.0 652.7 694.1 13120.2 13561.4 13585.3 13701.7 13958.8 14200.7 14918.9 15007.9 15026.2 15026.6 757.1 780.1 782.7 785.3 786.2 630.1 743.7 759.8 760.2 774.0 800.1 803.3 804.5 804.9 805.9 799.6 800.8 801.1 801.4 801.6 476.0 572.3 580.5 583.9 594.4 13839.7 13855.9 13856.0 13857.4 13860.8 12093.3 12517.6 12669.9 12889.9 13423.0 11870.8 11972.4 12022.8 12197.0 12862.4 4091.2 4133.2 4134.9 4281.4 4288.1 54609.7 56915.1 56971.7 62125.4 63095.4 10297.9 10787.6 10853.7 10874.0 10895.3 18929.6 18929.7 18936.1 18941.3 18962.1 6803.2 6876.6 6883.5 7007.4 7267.5 3757.7 3764.4 3771.1 3834.5 3851.6 2892.0 3011.0 3013.2 3025.5 3026.9 499.5 502.6 503.7 517.6 519.3 463.7 524.3 540.8 543.8 546.4 7872.5 7985.4 8035.9 8181.5 8355.2 429.8 444.5 455.5 464.4 550.2 3859.5 3953.1 3972.6 4021.8 4136.9 53156.5 55275.4 55638.4 55702.4 55985.7 81470.0 82158.6 82636.2 82953.6 84082.2 73247.0 73362.0 76139.2 76295.0 77754.0 7434.3 7613.4 7758.1 7803.2 7810.6 44424.3 44557.6 44998.8 46311.3 46643.5 57456.1 66684.7 67444.7 68641.7 71362.6 9972.0 10031.6 10049.5 10159.1 10411.6 11280.7 11333.4 11349.4 11388.5 12049.4 9860.4 10322.2 10702.3 10871.1 10953.3 9762.0 10472.0 11157.7 11311.1 11348.7 11647.6 11815.4 11949.4 12290.3 12395.9 9809.1 9903.2 10187.8 10466.3 10869.3 8944.3 9645.2 9757.8 9814.3 9828.4 15:47:29 INFO - PROCESS | 10632 | -------- Summary: end -------- 15:47:29 INFO - PROCESS | 10632 | 15:47:30 INFO - TEST-INFO | 10632: exit 0 15:47:30 INFO - TEST-OK | dromaeo_css | took 1120298ms 15:47:30 INFO - TEST-START | kraken 15:47:30 INFO - operating with platform_type : linux_ 15:47:30 INFO - Initialising browser for kraken test... 15:47:30 INFO - TEST-INFO | started process 10822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDFvMaN/profile http://localhost:36593/getInfo.html) 15:47:35 INFO - PROCESS | 10822 | __metrics Screen width/height:1600/1200 15:47:35 INFO - PROCESS | 10822 | colorDepth:24 15:47:35 INFO - PROCESS | 10822 | Browser inner width/height: 1024/697 15:47:35 INFO - PROCESS | 10822 | __metrics 15:47:36 INFO - PROCESS | 10822 | 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:47:36 INFO - PROCESS | 10822 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:47:36 INFO - TEST-INFO | 10822: exit 0 15:47:36 INFO - Browser initialized. 15:47:36 INFO - Running cycle 1/1 for kraken test... 15:47:36 INFO - TEST-INFO | started process 10903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDFvMaN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:48 INFO - PROCESS | 10903 | RSS: Main: 157650944 15:47:48 INFO - PROCESS | 10903 | 15:47:51 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36593/tests/kraken/driver.html?testName=audio-beat-detection) 15:47:52 INFO - PROCESS | 10903 | RSS: Main: 213835776 15:47:52 INFO - PROCESS | 10903 | 15:47:55 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36593/tests/kraken/driver.html?testName=audio-dft) 15:47:55 INFO - PROCESS | 10903 | RSS: Main: 191561728 15:47:55 INFO - PROCESS | 10903 | 15:47:59 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36593/tests/kraken/driver.html?testName=audio-fft) 15:47:59 INFO - PROCESS | 10903 | RSS: Main: 272900096 15:47:59 INFO - PROCESS | 10903 | 15:48:01 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36593/tests/kraken/driver.html?testName=audio-oscillator) 15:48:02 INFO - PROCESS | 10903 | RSS: Main: 180723712 15:48:02 INFO - PROCESS | 10903 | 15:48:04 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36593/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:48:05 INFO - PROCESS | 10903 | RSS: Main: 184758272 15:48:05 INFO - PROCESS | 10903 | 15:48:08 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36593/tests/kraken/driver.html?testName=imaging-darkroom) 15:48:09 INFO - PROCESS | 10903 | RSS: Main: 221544448 15:48:09 INFO - PROCESS | 10903 | 15:48:12 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36593/tests/kraken/driver.html?testName=imaging-desaturate) 15:48:13 INFO - PROCESS | 10903 | RSS: Main: 220073984 15:48:13 INFO - PROCESS | 10903 | 15:48:16 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36593/tests/kraken/driver.html?testName=json-parse-financial) 15:48:16 INFO - PROCESS | 10903 | RSS: Main: 220778496 15:48:16 INFO - PROCESS | 10903 | 15:48:18 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36593/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:48:19 INFO - PROCESS | 10903 | RSS: Main: 185565184 15:48:19 INFO - PROCESS | 10903 | 15:48:21 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:48:21 INFO - PROCESS | 10903 | RSS: Main: 191184896 15:48:21 INFO - PROCESS | 10903 | 15:48:23 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:48:24 INFO - PROCESS | 10903 | RSS: Main: 194293760 15:48:24 INFO - PROCESS | 10903 | 15:48:27 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:48:27 INFO - PROCESS | 10903 | RSS: Main: 189960192 15:48:27 INFO - PROCESS | 10903 | 15:48:30 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:48:31 INFO - PROCESS | 10903 | RSS: Main: 192864256 15:48:31 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | Cycle 1(1): loaded http://localhost:36593/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36593/tests/kraken/driver.html?testName=ai-astar) 15:48:33 INFO - PROCESS | 10903 | RSS: Main: 244850688 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | __start_tp_report 15:48:33 INFO - PROCESS | 10903 | _x_x_mozilla_page_load 15:48:33 INFO - PROCESS | 10903 | _x_x_mozilla_page_load_details 15:48:33 INFO - PROCESS | 10903 | |i|pagename|runs| 15:48:33 INFO - PROCESS | 10903 | |0;ai-astar;105;114;112;114;115;114;114;113;112;112 15:48:33 INFO - PROCESS | 10903 | |1;audio-beat-detection;152;121;123;185;123;123;145;122;162;124 15:48:33 INFO - PROCESS | 10903 | |2;audio-dft;161;162;161;161;162;162;162;161;160;163 15:48:33 INFO - PROCESS | 10903 | |3;audio-fft;76;75;76;105;76;75;76;76;99;75 15:48:33 INFO - PROCESS | 10903 | |4;audio-oscillator;85;85;138;85;86;85;116;85;85;121 15:48:33 INFO - PROCESS | 10903 | |5;imaging-gaussian-blur;117;117;117;118;117;117;118;117;117;117 15:48:33 INFO - PROCESS | 10903 | |6;imaging-darkroom;149;149;146;147;147;148;147;147;146;146 15:48:33 INFO - PROCESS | 10903 | |7;imaging-desaturate;94;95;95;95;95;96;95;95;95;95 15:48:33 INFO - PROCESS | 10903 | |8;json-parse-financial;63;63;62;63;64;64;63;64;63;63 15:48:33 INFO - PROCESS | 10903 | |9;json-stringify-tinderbox;64;65;66;65;65;65;65;65;65;65 15:48:33 INFO - PROCESS | 10903 | |10;stanford-crypto-aes;79;78;78;80;80;89;79;78;79;80 15:48:33 INFO - PROCESS | 10903 | |11;stanford-crypto-ccm;145;133;129;139;131;128;137;139;148;133 15:48:33 INFO - PROCESS | 10903 | |12;stanford-crypto-pbkdf2;162;165;162;163;163;167;162;162;162;163 15:48:33 INFO - PROCESS | 10903 | |13;stanford-crypto-sha256-iterative;68;67;67;67;66;66;74;65;66;66 15:48:33 INFO - PROCESS | 10903 | __end_tp_report 15:48:33 INFO - PROCESS | 10903 | __start_cc_report 15:48:33 INFO - PROCESS | 10903 | _x_x_mozilla_cycle_collect,1938 15:48:33 INFO - PROCESS | 10903 | __end_cc_report 15:48:33 INFO - PROCESS | 10903 | __startTimestamp1470696513299__endTimestamp 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | ------- Summary: start ------- 15:48:33 INFO - PROCESS | 10903 | Number of tests: 14 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#0] ai-astar Cycles:10 Average:112.50 Median:114.00 stddev:2.84 (2.5%) stddev-sans-first:1.12 15:48:33 INFO - PROCESS | 10903 | Values: 105.0 114.0 112.0 114.0 115.0 114.0 114.0 113.0 112.0 112.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:134.50 stddev:22.23 (16.5%) stddev-sans-first:22.99 15:48:33 INFO - PROCESS | 10903 | Values: 152.0 121.0 123.0 185.0 123.0 123.0 145.0 122.0 162.0 124.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 15:48:33 INFO - PROCESS | 10903 | Values: 161.0 162.0 161.0 161.0 162.0 162.0 162.0 161.0 160.0 163.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:11.22 (14.8%) stddev-sans-first:11.76 15:48:33 INFO - PROCESS | 10903 | Values: 76.0 75.0 76.0 105.0 76.0 75.0 76.0 76.0 99.0 75.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:85.00 stddev:20.01 (23.5%) stddev-sans-first:20.74 15:48:33 INFO - PROCESS | 10903 | Values: 85.0 85.0 138.0 85.0 86.0 85.0 116.0 85.0 85.0 121.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:48:33 INFO - PROCESS | 10903 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.14 (0.8%) stddev-sans-first:1.00 15:48:33 INFO - PROCESS | 10903 | Values: 149.0 149.0 146.0 147.0 147.0 148.0 147.0 147.0 146.0 146.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 15:48:33 INFO - PROCESS | 10903 | Values: 94.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 15:48:33 INFO - PROCESS | 10903 | Values: 63.0 63.0 62.0 63.0 64.0 64.0 63.0 64.0 63.0 63.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 15:48:33 INFO - PROCESS | 10903 | Values: 64.0 65.0 66.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:3.27 (4.1%) stddev-sans-first:3.44 15:48:33 INFO - PROCESS | 10903 | Values: 79.0 78.0 78.0 80.0 80.0 89.0 79.0 78.0 79.0 80.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:138.00 stddev:6.66 (4.8%) stddev-sans-first:6.26 15:48:33 INFO - PROCESS | 10903 | Values: 145.0 133.0 129.0 139.0 131.0 128.0 137.0 139.0 148.0 133.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:163.00 stddev:1.66 (1.0%) stddev-sans-first:1.72 15:48:33 INFO - PROCESS | 10903 | Values: 162.0 165.0 162.0 163.0 163.0 167.0 162.0 162.0 162.0 163.0 15:48:33 INFO - PROCESS | 10903 | 15:48:33 INFO - PROCESS | 10903 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:2.53 (3.8%) stddev-sans-first:2.67 15:48:33 INFO - PROCESS | 10903 | Values: 68.0 67.0 67.0 67.0 66.0 66.0 74.0 65.0 66.0 66.0 15:48:33 INFO - PROCESS | 10903 | -------- Summary: end -------- 15:48:33 INFO - PROCESS | 10903 | 15:48:34 INFO - TEST-INFO | 10903: exit 0 15:48:34 INFO - TEST-OK | kraken | took 63646ms 15:48:34 INFO - SUITE-END | took 1183s 15:48:34 INFO - Completed test suite (00:19:43) 15:48:34 INFO - javascript benchmark 15:48:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2634.657494525714, 2647.3911000469893, 2673.692525359583, 2680.3031211884904, 2691.1522870295858, 4556.020455602045, 4620.97689511552, 4718.764061796909, 4870.391201099863, 4887.7311747853855, 4767.451743049551, 4775.976120119403, 4814.518548145193, 4849.563539281464, 4860.902781944359, 4336.609705126538, 4341.50072741634, 4429.800658970338, 4442.155990361821, 4517.932231016521, 4450.554944505556, 4494.505604383515, 4511.887202819923, 4531.818727250906, 4533.8413155539565, 4288.270993931039, 4333.674974376934, 4336.522982471925, 4360.65114790816, 4445.377647129404, 4378.365137055141, 4384.342348647697, 4403.933940990888, 4491.6855820092605, 4500.3249512573375, 4415.889602759957, 4420.248557745191, 4434.312681534368, 4464.6874718769695, 4467.173572889026, 4641.605463535603, 4826.444958829745, 4833.7583120844165, 4839.516048395168, 4890.877728056827, 5056.0, 5137.871553211162, 5140.794368225266, 5183.559397451221, 5219.0, 5212.40057393397, 5235.0, 5241.842744717665, 5248.763805628756, 5249.265102885599, 4659.790309436084, 4894.26586012094, 4897.902041959141, 4902.093112774148, 4902.1176188285735, 5227.634065615371, 5306.734663266821, 5307.655002424907, 5388.057090009311, 5405.351357837085, 5084.186530155157, 5107.489251074937, 5141.897162056738, 5153.613478989091, 5167.741612919339, 4974.726390048582, 5227.477252274818, 5238.659487133325, 5245.763940622604, 5247.055530004636, 5051.1413059779925, 5060.7722652480725, 5114.130597798383, 5192.818251361185, 5194.35070616173, 5119.667221630582, 5124.128898087334, 5135.6148288878485, 5138.152204886227, 5176.689398636094, 4430.158269928703, 4432.645388368923, 4433.91369114569, 4473.239549276631, 4481.686281960231, 3763.2473505298503, 3780.281746468162, 3807.447920051577, 3859.4789703390243, 3876.7480113792512, 4435.179491794029, 4436.556344365537, 4456.17560751262, 4473.619742321939, 4480.910381792346, 5200.375954885438, 5219.060569097523, 5226.529612334908, 5226.790928362823, 5275.366955965232, 4084.6936479764136, 4212.515560710496, 4326.307790753464, 4427.8228870844805, 4440.4227450431235, 4639.257718764981, 4663.020765639186, 4666.090112428112, 4694.577488026094, 4706.576408123286, 4854.393200849894, 4857.174280372275, 4858.028394321221, 4862.625598741262, 4888.364512613338, 4974.228994505857, 4974.726390048438, 4997.700137991732, 5162.432132465501, 5197.974010129925, 4709.5055019223855, 4727.4799772025735, 4730.385049943486, 4765.9046819064115, 4772.427308722976, 4953.678010929278, 4965.1311020571975, 4973.104841128632, 4975.477574854588, 5009.9248511271635, 4968.4286307075145, 5040.647154699136, 5082.669626474268, 5091.6181286402925, 5168.121419358643, 5039.168537191323, 5042.823501177441, 5047.318611987482, 5070.442251352422, 5076.7207803571155, 5036.974815125901, 5052.418971818288, 5054.064997975239, 5061.164907790173, 5105.31078304439, 4731.526847315241, 4900.705957642553, 4944.381952255968, 4965.304857319906, 4966.155753521982, 5099.209622508517, 5101.107306221322, 5101.9489805101475, 5140.254663073759, 5157.200633901752, 4979.526944940225, 4981.252812078073, 4981.850544483671, 4994.625403094855, 5040.924386134137], "value": 4726.780041871205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.7535109200859, 564.7825587148947, 565.8189379398591, 572.6764378126359, 572.7193675099203, 28547.0, 41380.37929431061, 42755.78622106889, 42859.78570107153, 42869.7856510717, 39364.80317598408, 39828.40257396134, 39873.60126398735, 39916.21005479347, 39931.80034099833, 7588.127365352972, 7714.382849372037, 7722.227777222289, 7723.034620672416, 7724.111727151393, 7375.151857536398, 9786.90213097867, 10024.548895299693, 10043.0, 10330.483475826217, 9358.0, 9431.952840235823, 9440.480773557452, 9445.71662850112, 9459.57431915562, 9913.504324783768, 9922.404655720607, 9978.999999999964, 9982.600695972225, 9985.151262142726, 9868.358556693864, 9936.552855121538, 9939.453330066843, 9940.304178707493, 9945.055219754111, 38168.42747358764, 38585.80707096447, 38652.420213696816, 38703.22593548113, 38703.22593548113, 3048.695130486956, 3126.5622812806228, 3425.640307767673, 3478.217401084761, 3805.1818858945185, 27350.589741153897, 28190.295242618893, 29311.608473891745, 29863.25341866449, 29999.700002999907, 3521.7710848795004, 3556.395412779834, 3612.801295928723, 3636.5272514572935, 3757.511523501955, 258356.0, 261724.0, 261893.0, 263576.0, 264119.9999999981, 56276.718616407474, 56910.430895690515, 57031.42968570261, 57155.0, 57221.0, 33282.16794580106, 34383.31233375319, 34384.31231375408, 36382.454263186075, 36413.63586364155, 13856.099353541988, 13864.930675346559, 13971.301434928213, 13976.161430314214, 13985.510507132201, 9895.505224738734, 9911.900880991241, 9932.0, 10093.596256149818, 10365.429901355346, 16978.915105424392, 16990.235439405256, 17117.400890969006, 19491.0, 19670.311539096063, 7196.028536147658, 7320.96339518299, 7330.8900366494545, 7414.814629634302, 7470.4770666052855, 765.4106338119617, 869.6130222051127, 911.1708345405649, 912.9224015958586, 944.1125342178331, 27095.322616934733, 27543.17370478889, 27769.305767355978, 27886.3028424291, 28069.0, 856.6273670953155, 867.1458613265928, 916.4821875640375, 917.6512925088557, 938.648006997376, 14133.434662613587, 14515.0, 14588.85411145875, 14707.191104489355, 14861.851381486047, 408575.0, 413216.0, 413474.0, 413657.0, 414022.0, 49291.753541232065, 49855.750721246884, 49971.50028499741, 50007.74996124996, 50061.74969125204, 1125.2573301621055, 1158.5250047480492, 1176.4294317256295, 1180.3331117918349, 1181.2144923625865, 25806.483870322114, 26956.191314260603, 27019.0, 27420.45159096767, 27533.449331013668, 43410.78294608507, 43516.129677406905, 43631.78184109059, 43633.345499816896, 43652.12695746131, 340708.0, 341367.0, 342214.0, 343140.0, 343218.0, 22835.543289134457, 23496.647550286416, 24236.51526969486, 25931.22206333813, 26324.4735105293, 37966.24067518689, 40322.59677403188, 40438.79780601078, 40674.0, 41029.0, 35335.64664353323, 35471.29057418889, 35724.46413303854, 35772.821135894155, 36349.636503634625, 35506.289874202885, 35953.64046359608, 35979.761821436194, 36041.27917441584, 36047.0, 34035.14912127118, 34094.65905340915, 34593.827030865694, 34781.304373912884, 34814.65185348114, 29230.853845731486, 34069.829650851585, 34161.75463330979, 34205.65794342124, 34327.65672343245, 24749.505009900062, 25777.226683199533, 26650.0, 26880.462390751683, 27347.179584612495], "value": 17751.641093705784, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.8898607952416, 357.9767315124517, 366.27477594363154, 367.35529146348165, 378.5835580861053, 12524.18592791469, 15058.548243552697, 16582.83417165828, 16859.072750998705, 17511.912440437813, 14307.427702891871, 14777.408903643842, 14818.703625927476, 14857.479988200388, 15421.0, 4616.238320677093, 4903.82836600719, 5151.227315902607, 5177.042247184264, 5181.4300426953005, 3482.320947415255, 5086.059079070365, 5169.3279873616375, 5184.274201611777, 5664.178694089354, 5944.197533332988, 5967.134765459005, 5978.551608629348, 5983.491403230731, 6031.849203769897, 5302.628815982883, 5393.919091213635, 5437.510624043855, 5877.500412464967, 5977.043673012319, 5266.183741520071, 5273.2617433559335, 5275.551578115865, 5278.208268759717, 5278.894422111574, 12626.55807046749, 14937.477188298357, 20884.164633414795, 21085.0, 21129.788702112935, 1856.6843636581814, 1869.2242719271485, 1994.450483721257, 2007.7289565908561, 2229.27548546723, 9012.0, 9485.573149208301, 9691.951540242324, 9826.90173098267, 9869.407835529892, 1754.0, 1758.7449819776061, 1801.711726123827, 1814.637072585488, 1816.7184086466618, 68338.31661683369, 69604.30395696028, 70855.64572177158, 72351.63824180845, 72826.27173728248, 10240.436775977392, 10253.128484078788, 10424.947875260727, 10520.158387328995, 10551.419671917965, 14018.579442616738, 14023.439062437388, 14028.368723407471, 14048.85951140496, 14052.508162214273, 7243.0, 7259.419246460376, 7265.0, 7320.853582928312, 7517.473776835678, 6824.727010919508, 6834.2482326944, 6856.348646878639, 6985.371316581431, 7056.2359410147465, 3936.4292177634084, 4187.664986801049, 4257.063446041866, 4389.144116897174, 4529.9547004530195, 1805.8826176298762, 1840.4386662068198, 1855.7587513623412, 1857.3220774417512, 1907.8282954534159, 725.6263024542365, 822.8971378577678, 827.2058823529479, 914.3325372478129, 921.6497730862363, 16232.75650865238, 16949.32202711878, 17202.569935751708, 17849.37527186542, 17989.910050449664, 848.6902280667512, 854.9786255343541, 865.16079402979, 877.5612193903048, 920.447731361178, 9519.571619277149, 9523.857142142873, 9830.115289623967, 9870.457124858058, 9891.455969921722, 406538.0, 407872.0, 417176.0, 419758.0, 420104.0, 76233.0, 76649.0, 77077.0, 78927.0, 84108.0, 794.3684770607266, 797.2704974947974, 799.580220384303, 799.8280369720538, 909.2135302963021, 15347.232638367814, 16165.27256273447, 16242.593935151715, 16462.75305870437, 16512.66974660524, 29716.55425168581, 39000.41499377568, 39139.804300978314, 39249.411258831715, 39564.80217598894, 54027.459725402245, 54440.0, 56875.14687279611, 57768.0, 58005.4199458, 6326.746930122744, 6513.8697226056165, 6563.704633291418, 6787.932120678927, 6818.8636227276165, 8184.468009579358, 9871.950640246752, 10882.891171088504, 11212.439378030902, 11428.771424571629, 9328.440293582407, 9425.952870235878, 9451.102145296185, 9579.137877590772, 9853.901460985298, 9763.170130538983, 9908.801823963337, 10033.949830250802, 10091.848622270525, 10192.949035254776, 9456.338056336266, 9519.57161927701, 9614.278929080487, 9747.951260243653, 10188.490575471049, 6609.570377925419, 6651.534392592472, 6739.59562426256, 6748.223954245128, 6964.303569642995, 8839.867401989104, 9401.10689484498, 9659.372140810825, 9684.46735429558, 9850.16273616722], "value": 8059.537195919002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1212.4786341872996, 1213.1750409721396, 1277.3357853915963, 1368.9452421903125, 1378.221304962696, 6208.255009398873, 7510.361619262362, 7735.806604834875, 7823.608819559027, 8028.35773138149, 7844.215578442153, 7920.009998750156, 7920.524768513878, 7923.48497347672, 7929.365650747941, 2096.3082182879652, 2105.1789801977243, 2107.915683372671, 2108.325335892515, 2111.556573119639, 2117.745870495535, 2147.8281737460966, 2174.4346469917778, 2177.4556360909774, 2177.68423578581, 2132.722746043012, 2152.1929276521305, 2161.8702877827304, 2163.751168615606, 2164.2425151197112, 2132.1364847236896, 2137.1558234497443, 2139.0801955159277, 2140.122549754609, 2140.83943704222, 2059.794020597943, 2118.0998075817715, 2118.533922537055, 2119.7562280337806, 2121.586290673319, 7794.9220507795335, 7942.245486678814, 7944.682212711485, 7944.801379965489, 7947.08608510023, 4245.936310955338, 4419.204543182226, 4422.115576884636, 4447.466304043503, 4457.509673935864, 2718.497078040569, 2862.9856850715823, 2932.2669332666833, 2935.500964835983, 2936.368680733631, 872.6596627315353, 885.7077164535688, 889.2707979456848, 898.5192921786812, 901.2519608724742, 10785.406802625852, 10842.783144337149, 10871.67384978452, 10871.891281087166, 10876.619318323821, 3701.61133081028, 3789.242151569697, 3996.8401263949117, 4000.3599424092586, 4027.456293400412, 8506.234438900528, 8532.786680333042, 8532.914670853335, 8540.74377768668, 8546.444481108709, 4367.388565600819, 4393.165298593321, 4395.8021889014435, 4398.098389830077, 4398.142362239332, 3382.086836554166, 3865.1110244643887, 3999.7800120993043, 4080.9387859182134, 4127.938080928789, 3080.9383812323626, 3106.08370530694, 3188.457962146441, 3193.4890417533084, 3204.279037216608, 994.3636072913341, 1001.5993602559034, 1004.1615251265275, 1006.8036732837027, 1045.0698877998586, 595.2499850188793, 604.9909251361233, 702.78565037664, 718.6730037832774, 721.1274358026847, 4795.256735206049, 4854.80580776765, 5009.148444764399, 5131.846044618667, 5177.663451875616, 779.7582749347607, 791.2206476620524, 821.2075347289893, 853.7918844834613, 887.7070566712969, 3216.453202955503, 3403.9149021274666, 3429.3998550253605, 3478.130467383154, 3572.517710109154, 9318.0, 9617.0, 9826.115649591571, 9845.655402060893, 9853.556589953469, 9869.21046316293, 9879.407235565888, 9884.456354900405, 9904.7028589141, 9967.850482242773, 7373.8156546086775, 7376.594287314249, 7430.554166750012, 7517.285857843496, 7522.059742532184, 5085.211792172219, 5131.56381707559, 5192.1952097424955, 5234.476552344735, 5240.423553409199, 8868.15752503511, 8868.423552469068, 9093.954530227307, 9110.908890911007, 9245.214156796748, 9069.77325566866, 9207.539623018956, 9280.535973201448, 9341.813163736822, 9348.719538413858, 5497.5601951842955, 6228.377162283917, 6312.0, 6318.494520438262, 6319.3364696706185, 6287.937120628918, 6429.164208652845, 6831.385175334244, 6973.895391569232, 7007.089078419773, 5968.373320801254, 6238.844028899314, 6263.185785847811, 6392.328805475358, 6515.413612774873, 6172.567920245539, 6348.523860710373, 6356.173697419491, 6361.363863613602, 6373.840653983688, 5659.6321239119325, 5672.319321681424, 5698.629589076862, 5709.743061562157, 5736.19693242938, 4272.252355837778, 4329.956700432956, 4388.868333949987, 4394.077243778715, 4519.367288579536, 4396.252637051773, 4413.602775750198, 4448.154850578379, 4505.864824055284, 4522.954770452254], "value": 3954.6083297519936, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.3982548132331, 287.44666516954857, 290.3756922616376, 291.93577412969165, 293.3897493214115, 4942.357493525846, 4950.207966725325, 4952.603791696664, 4961.751912404384, 4967.304577359173, 4819.590334821544, 4829.299751536025, 4849.321095046696, 4886.218205087187, 4908.582770464514, 3579.284143171363, 3699.500567423404, 3707.3697471429923, 3792.5448946126503, 3819.6562309392148, 3815.561210460791, 3818.198178382529, 3822.7132965027595, 3839.9232015359817, 3850.84596616135, 3782.7162962777766, 3804.828782704771, 3812.294725475796, 3814.256220037094, 3824.7131465140087, 3621.9818900905457, 3643.544556930384, 3712.424574191005, 3759.755615884959, 3816.6755825754844, 3265.42855000374, 3268.3790079884748, 3274.4106060909025, 3274.5743053403144, 3277.2953814929665, 4704.270838020078, 4718.41019872516, 4734.958309171977, 4754.144254034272, 4780.80876764929, 4083.6528895043957, 4238.300680387732, 4261.019965407942, 4268.701190916637, 4293.420388247578, 5007.12375334315, 5020.0, 5020.221865610836, 5025.949740502621, 5067.137067556819, 3593.5328407306883, 3604.585472670624, 3607.5670919489567, 3609.043603445063, 3610.259896721192, 5123.666961647481, 5291.3121294231505, 5291.973540132313, 5307.840764777063, 5333.759980800874, 3614.331348700499, 3620.475031120473, 3627.72792040598, 3628.1473853644375, 3633.94549081764, 3678.448232765053, 3705.5738590061947, 3709.8145092745253, 3710.9814450927574, 3717.0893131182493, 4113.650339721097, 4246.723962942398, 4266.914661706748, 4275.786210689453, 4282.978585107055, 4967.677100988424, 4975.751212439363, 5033.723145227048, 5049.368828896388, 5081.695098294114, 1249.3565813606, 1271.955481558141, 1272.1349482351904, 1281.6475469245845, 1282.9928506122649, 754.8339365339616, 788.6096382290802, 795.1055063054064, 795.777182388932, 798.66456088443, 4325.567443255649, 4435.955640443619, 4459.821607135743, 4464.866054018385, 4489.528599497071, 5189.662671926313, 5191.870203244949, 5201.517567493246, 5204.453532379046, 5210.583153347723, 4435.578620031092, 4443.0, 4445.888852778642, 4445.977770111193, 4458.770614692654, 2592.949855308603, 2759.4619049285598, 2760.2961244882426, 2778.5554311310498, 2781.694013658496, 4882.584980276645, 4904.975475122602, 4924.507549245047, 4947.752612369367, 4959.752012399365, 5234.602282616789, 5247.685138891678, 5302.946970530245, 5350.732463376816, 5358.758855851496, 4794.95205047952, 4876.0, 4882.609391248691, 4903.411590609078, 4929.309896614405, 5263.289455923401, 5267.5522580579545, 5272.2882410874045, 5299.0, 5337.332833395825, 4860.489648586848, 5107.79568817258, 5148.613853960893, 5180.171172612515, 5199.142141546754, 4929.088118698052, 4936.086823937583, 4940.456549779449, 4951.801927922843, 4959.504049595012, 3645.505342809435, 3649.105969037603, 3696.5564132304294, 3710.6103859094405, 3721.7580857244193, 5109.1059064662795, 5217.34783152106, 5313.9468605315, 5325.360956685223, 5330.920036199383, 5007.899842003067, 5008.472415913181, 5041.974790126026, 5051.595872330278, 5060.493950605058, 5046.823361182341, 5050.636328191441, 5063.87340316495, 5082.3138876252715, 5133.332666753298, 4742.766880610997, 4980.431164183271, 4984.526469985346, 4989.575886049582, 4993.875153121201, 4857.95142048575, 4946.431160416598, 5086.796528138981, 5113.53978141969, 5114.667546609458, 4947.6536642434685, 4966.205407134986, 4983.875403114806, 5004.974975125101, 5013.699178049183], "value": 3797.699191093922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.8370681769331, 622.3247776162863, 644.9580777249479, 652.6964961292999, 694.0942070597868, 13120.21278723276, 13561.389737461814, 13585.252811095386, 13701.65745856354, 13958.790618140736, 14200.715985680281, 14918.925405372958, 15007.924960375183, 15026.248687565605, 15026.624334391618, 757.1330826203979, 780.0872978615006, 782.7377828427484, 785.2579312549643, 786.2334224131455, 630.124127462827, 743.7396911081105, 759.840433508961, 760.2473551184343, 773.957432341221, 800.0639252075057, 803.3211935914143, 804.5454318310166, 804.8752443371258, 805.9032916050082, 799.580220384297, 800.8478389106081, 801.1107670485757, 801.3949468151508, 801.5631480842954, 476.0384024270988, 572.2732130194629, 580.5413723158741, 583.8627922438224, 594.4204400709317, 13839.723205535933, 13855.930720346334, 13856.0, 13857.4457021719, 13860.792088118686, 12093.334866582334, 12517.624471265786, 12669.873301266962, 12889.935550322189, 13423.0, 11870.762584748256, 11972.401379930969, 12022.819657705142, 12197.024238060934, 12862.421191046426, 4091.2226676931286, 4133.1940271647045, 4134.9118695059105, 4281.422008028907, 4288.120935208275, 54609.72695136539, 56915.14627280594, 56971.71514142403, 62125.37874621241, 63095.36904630941, 10297.9485102574, 10787.6224332148, 10853.728656783644, 10874.02133807961, 10895.346279223271, 18929.621407571773, 18929.716054259196, 18936.053197340352, 18941.33705320307, 18962.051897405076, 6803.183617965777, 6876.621785801829, 6883.45691843391, 7007.4394048476, 7267.491275610762, 3757.6993840492696, 3764.378877485184, 3771.07608635886, 3834.4631751554803, 3851.557070936822, 2891.9855400722863, 3010.969890301069, 3013.231625935416, 3025.4554180247765, 3026.8789248429816, 499.45169973083154, 502.5979216626684, 503.67764630636424, 517.6195496310208, 519.3378442485861, 463.73798803675817, 524.3419508516863, 540.8350453111839, 543.7824870051932, 546.3580293155605, 7872.488288261245, 7985.361171106298, 8035.879461808077, 8181.509109453452, 8355.206255405848, 429.84310726584573, 444.4999375702335, 455.4693781744297, 464.37773383665376, 550.1829782772579, 3859.4789703390243, 3953.1303113315025, 3972.56301806801, 4021.8190181441905, 4136.896577585524, 53156.46843531515, 55275.44724552784, 55638.44361556332, 55702.442975570535, 55985.720071400196, 81470.0, 82158.58920705358, 82636.17363826405, 82953.58523207346, 84082.15917840866, 73247.0, 73362.0, 76139.23860761544, 76295.0, 77753.99999999886, 7434.2565743427385, 7613.428992825449, 7758.146603873683, 7803.219678032151, 7810.567762487528, 44424.33363499485, 44557.55442445534, 44998.77500612476, 46311.305330419396, 46643.53356466392, 57456.1381579285, 66684.66657666875, 67444.66277668776, 68641.65679171572, 71362.64318678374, 9972.0, 10031.598736050477, 10049.497525123861, 10159.136473399843, 10411.635592754217, 11280.661580152608, 11333.433328333385, 11349.432528373383, 11388.544458221579, 12049.397530123633, 9860.408375497493, 10322.174226062047, 10702.304350217522, 10871.130309575374, 10953.34279943206, 9761.951190244003, 10471.952804719467, 11157.66527004191, 11311.095112391153, 11348.659540213806, 11647.59233426826, 11815.409229538316, 11949.402529873298, 12290.262584244974, 12395.93802030984, 9809.068138526829, 9903.158231550111, 10187.847182292124, 10466.319689220176, 10869.347839129678, 8944.329175312008, 9645.228381729305, 9757.756056098655, 9814.312998090065, 9828.4102953823], "value": 6416.928326516792, "unit": "score"}], "name": "dromaeo_css", "value": 6343.847848330379, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 112.0, 114.0, 115.0, 114.0, 114.0, 113.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 121.0, 123.0, 185.0, 123.0, 123.0, 145.0, 122.0, 162.0, 124.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 162.0, 162.0, 162.0, 161.0, 160.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 105.0, 76.0, 75.0, 76.0, 76.0, 99.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 138.0, 85.0, 86.0, 85.0, 116.0, 85.0, 85.0, 121.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 146.0, 147.0, 147.0, 148.0, 147.0, 147.0, 146.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 64.0, 64.0, 63.0, 64.0, 63.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 80.0, 80.0, 89.0, 79.0, 78.0, 79.0, 80.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 133.0, 129.0, 139.0, 131.0, 128.0, 137.0, 139.0, 148.0, 133.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 162.0, 163.0, 163.0, 167.0, 162.0, 162.0, 162.0, 163.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 74.0, 65.0, 66.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1524.1000000000001, "alertThreshold": 2.0}]} 15:48:34 INFO - Return code: 0 15:48:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:48:34 INFO - # TBPL SUCCESS # 15:48:34 INFO - Running post-action listener: _resource_record_post_action 15:48:34 INFO - [mozharness: 2016-08-08 22:48:34.636516Z] Finished run-tests step (success) 15:48:34 INFO - Running post-run listener: _resource_record_post_run 15:48:34 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 200704; Write bytes: 537169920; Read time: 156; Write time: 481288 15:48:34 INFO - TinderboxPrint: CPU usage
13.0% 15:48:34 INFO - TinderboxPrint: I/O read bytes / time
200,704 / 156 15:48:34 INFO - TinderboxPrint: I/O write bytes / time
537,169,920 / 481,288 15:48:34 INFO - TinderboxPrint: CPU idle
8,334.3 (87.0%) 15:48:34 INFO - TinderboxPrint: CPU user
1,198.5 (12.5%) 15:48:34 INFO - TinderboxPrint: Swap in / out
0 / 0 15:48:34 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 250466304; Read time: 0; Write time: 292484 15:48:34 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 196608; Write bytes: 286703616; Read time: 144; Write time: 188804 15:48:35 INFO - Running post-run listener: _upload_blobber_files 15:48:35 INFO - Blob upload gear active. 15:48:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:48:35 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:48:35 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:48:35 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:48:35 INFO - (blobuploader) - INFO - Open directory for files ... 15:48:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:48:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:48:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:48:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:48:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:48:36 INFO - (blobuploader) - INFO - Done attempting. 15:48:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:48:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:48:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:48:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:48:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:48:37 INFO - (blobuploader) - INFO - Done attempting. 15:48:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:48:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:48:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:48:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:48:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:48:40 INFO - (blobuploader) - INFO - Done attempting. 15:48:40 INFO - (blobuploader) - INFO - Iteration through files over. 15:48:40 INFO - Return code: 0 15:48:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:48:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:48:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/69998ce3019a0ef9bbb58e508ed9ec74a52a6c52afb383fc407239f947595d3f4843f5f51c5292a5959d0ca364a50e9143144bbd80299e751a70bab4c524791f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab96aa1e8b2ae2853abe1067fb5248484d279e4a558122b4c4f85b88a20d6027bb89fdb45c9cc0bb73b7e019f58b9c1d6b8ad84b0013b11bbd43084b392c05a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21a27720041fc689a470bb705aaedb2975deeecca00f2567590cdd6caa6b6c998ef8a62a4a017f05d486d394769968e6dd78098f282d8ee7af62cf16c03d567c"} 15:48:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:48:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:48:40 INFO - Contents: 15:48:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/69998ce3019a0ef9bbb58e508ed9ec74a52a6c52afb383fc407239f947595d3f4843f5f51c5292a5959d0ca364a50e9143144bbd80299e751a70bab4c524791f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab96aa1e8b2ae2853abe1067fb5248484d279e4a558122b4c4f85b88a20d6027bb89fdb45c9cc0bb73b7e019f58b9c1d6b8ad84b0013b11bbd43084b392c05a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21a27720041fc689a470bb705aaedb2975deeecca00f2567590cdd6caa6b6c998ef8a62a4a017f05d486d394769968e6dd78098f282d8ee7af62cf16c03d567c"} 15:48:40 INFO - Running post-run listener: copy_logs_to_upload_dir 15:48:40 INFO - Copying logs to upload dir... 15:48:40 INFO - mkdir: /builds/slave/test/build/upload/logs 15:48:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.781637 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-08 15:48:40.778149) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 15:48:40.781716) ========= 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=db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321 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/69998ce3019a0ef9bbb58e508ed9ec74a52a6c52afb383fc407239f947595d3f4843f5f51c5292a5959d0ca364a50e9143144bbd80299e751a70bab4c524791f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab96aa1e8b2ae2853abe1067fb5248484d279e4a558122b4c4f85b88a20d6027bb89fdb45c9cc0bb73b7e019f58b9c1d6b8ad84b0013b11bbd43084b392c05a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21a27720041fc689a470bb705aaedb2975deeecca00f2567590cdd6caa6b6c998ef8a62a4a017f05d486d394769968e6dd78098f282d8ee7af62cf16c03d567c"} build_url:https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/UVQYUJDqQi6EhaxucS6xgA/artifacts/public/build/firefox-51.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/69998ce3019a0ef9bbb58e508ed9ec74a52a6c52afb383fc407239f947595d3f4843f5f51c5292a5959d0ca364a50e9143144bbd80299e751a70bab4c524791f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab96aa1e8b2ae2853abe1067fb5248484d279e4a558122b4c4f85b88a20d6027bb89fdb45c9cc0bb73b7e019f58b9c1d6b8ad84b0013b11bbd43084b392c05a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21a27720041fc689a470bb705aaedb2975deeecca00f2567590cdd6caa6b6c998ef8a62a4a017f05d486d394769968e6dd78098f282d8ee7af62cf16c03d567c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 15:48:40.807211) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:48:40.807567) ========= 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=db7e7c15f3ca076d53731445000001ba-1470695121.839722-643407321 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:48:40.893501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 15:48:40.894298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 15:48:40.895016) ========= ========= Total master_lag: 0.17 =========