builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1469755669.45 results: success (0) buildid: 20160728171331 builduid: 733ecc7683be45a69c486c774e7c5d28 revision: b722fac1af4b88f9ed41f620b0eef2e851f04faa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.452941) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.453459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.453788) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.468169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.468442) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-28 18:27:50-- 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% 222M=0s 2016-07-28 18:27:50 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131312 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.613443) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.613755) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019117 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:49.663401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-28 18:27:49.663752) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b722fac1af4b88f9ed41f620b0eef2e851f04faa --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 b722fac1af4b88f9ed41f620b0eef2e851f04faa --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=3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-28 18:27:50,370 truncating revision to first 12 chars 2016-07-28 18:27:50,370 Setting DEBUG logging. 2016-07-28 18:27:50,370 attempt 1/10 2016-07-28 18:27:50,370 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b722fac1af4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-28 18:27:51,699 unpacking tar archive at: autoland-b722fac1af4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.650266 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-28 18:27:51.327531) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:51.327860) ========= script_repo_revision: b722fac1af4b88f9ed41f620b0eef2e851f04faa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:51.328261) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:51.328627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 18:27:51.337674) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-28 18:27:51.338036) ========= /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=3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:27:52 INFO - MultiFileLogger online at 20160728 18:27:52 in /builds/slave/test 18:27:52 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 18:27:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:27:52 INFO - {'append_to_log': False, 18:27:52 INFO - 'base_work_dir': '/builds/slave/test', 18:27:52 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 18:27:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:27:52 INFO - 'branch': 'Autoland-Non-PGO', 18:27:52 INFO - 'buildbot_json_path': 'buildprops.json', 18:27:52 INFO - 'config_files': ('talos/linux_config.py',), 18:27:52 INFO - 'default_actions': ('clobber', 18:27:52 INFO - 'read-buildbot-config', 18:27:52 INFO - 'download-and-extract', 18:27:52 INFO - 'populate-webroot', 18:27:52 INFO - 'create-virtualenv', 18:27:52 INFO - 'install', 18:27:52 INFO - 'run-tests'), 18:27:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:27:52 INFO - 'download_minidump_stackwalk': True, 18:27:52 INFO - 'download_symbols': 'ondemand', 18:27:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:27:52 INFO - 'tooltool.py': '/tools/tooltool.py', 18:27:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:27:52 INFO - '/tools/misc-python/virtualenv.py')}, 18:27:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:27:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:27:52 INFO - 'installer_path': 'installer.exe', 18:27:52 INFO - 'log_level': 'info', 18:27:52 INFO - 'log_name': 'talos', 18:27:52 INFO - 'log_to_console': True, 18:27:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:27:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:27:52 INFO - 'opt_config_files': (), 18:27:52 INFO - 'pip_index': False, 18:27:52 INFO - 'sps_profile': False, 18:27:52 INFO - 'sps_profile_interval': 0, 18:27:52 INFO - 'suite': 'dromaeojs', 18:27:52 INFO - 'system_bits': '32', 18:27:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:27:52 INFO - 'title': 'talos-linux64-ix-059', 18:27:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:27:52 INFO - 'use_talos_json': True, 18:27:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:27:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:27:52 INFO - 'work_dir': 'build'} 18:27:52 INFO - [mozharness: 2016-07-29 01:27:52.136850Z] Running clobber step. 18:27:52 INFO - Running pre-action listener: _resource_record_pre_action 18:27:52 INFO - Running main action method: clobber 18:27:52 INFO - rmtree: /builds/slave/test/build 18:27:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:27:53 INFO - Running post-action listener: _resource_record_post_action 18:27:53 INFO - [mozharness: 2016-07-29 01:27:53.117182Z] Finished clobber step (success) 18:27:53 INFO - [mozharness: 2016-07-29 01:27:53.117283Z] Running read-buildbot-config step. 18:27:53 INFO - Running pre-action listener: _resource_record_pre_action 18:27:53 INFO - Running main action method: read_buildbot_config 18:27:53 INFO - Using buildbot properties: 18:27:53 INFO - { 18:27:53 INFO - "project": "", 18:27:53 INFO - "product": "firefox", 18:27:53 INFO - "script_repo_revision": "production", 18:27:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 18:27:53 INFO - "repository": "", 18:27:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 18:27:53 INFO - "buildid": "20160728171331", 18:27:53 INFO - "pgo_build": "False", 18:27:53 INFO - "basedir": "/builds/slave/test", 18:27:53 INFO - "buildnumber": 154, 18:27:53 INFO - "slavename": "talos-linux64-ix-059", 18:27:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:27:53 INFO - "platform": "ubuntu64_hw", 18:27:53 INFO - "scheduler": "tests-autoland-linux64-talos", 18:27:53 INFO - "branch": "autoland", 18:27:53 INFO - "repo_path": "integration/autoland", 18:27:53 INFO - "revision": "b722fac1af4b88f9ed41f620b0eef2e851f04faa", 18:27:53 INFO - "stage_platform": "linux64", 18:27:53 INFO - "builduid": "733ecc7683be45a69c486c774e7c5d28", 18:27:53 INFO - "slavebuilddir": "test" 18:27:53 INFO - } 18:27:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:27:53 INFO - Running post-action listener: _resource_record_post_action 18:27:53 INFO - [mozharness: 2016-07-29 01:27:53.119745Z] Finished read-buildbot-config step (success) 18:27:53 INFO - [mozharness: 2016-07-29 01:27:53.119835Z] Running download-and-extract step. 18:27:53 INFO - Running pre-action listener: _resource_record_pre_action 18:27:53 INFO - Running main action method: download_and_extract 18:27:53 INFO - mkdir: /builds/slave/test/build/tests 18:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:53 INFO - https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:27:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:27:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:27:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:27:53 INFO - Downloaded 1361 bytes. 18:27:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:27:53 INFO - Using the following test package requirements: 18:27:53 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:53 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:27:53 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:27:53 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'jsshell-linux-x86_64.zip'], 18:27:53 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:27:53 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:53 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:27:53 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:27:53 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:27:53 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:53 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:27:53 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:53 INFO - https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:27:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:27:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:27:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:27:54 INFO - Downloaded 22749930 bytes. 18:27:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:27:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:27:55 INFO - Return code: 0 18:27:55 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:55 INFO - https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:27:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:27:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:27:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:27:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:27:56 INFO - Downloaded 22749930 bytes. 18:27:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:27:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:27:58 INFO - Return code: 0 18:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:58 INFO - https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:27:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:27:59 INFO - Downloaded 14413725 bytes. 18:27:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:27:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:27:59 INFO - Return code: 0 18:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:59 INFO - https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:27:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:27:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:27:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:28:00 INFO - Downloaded 57165176 bytes. 18:28:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:28:00 INFO - mkdir: /builds/slave/test/properties 18:28:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:28:00 INFO - Writing to file /builds/slave/test/properties/build_url 18:28:00 INFO - Contents: 18:28:00 INFO - build_url:https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:28:01 INFO - Running post-action listener: _resource_record_post_action 18:28:01 INFO - Running post-action listener: set_extra_try_arguments 18:28:01 INFO - [mozharness: 2016-07-29 01:28:01.308495Z] Finished download-and-extract step (success) 18:28:01 INFO - [mozharness: 2016-07-29 01:28:01.308728Z] Running populate-webroot step. 18:28:01 INFO - Running pre-action listener: _resource_record_pre_action 18:28:01 INFO - Running main action method: populate_webroot 18:28:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:28:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:28:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:28:01 INFO - '--tppagecycles', 18:28:01 INFO - '7'], 18:28:01 INFO - 'tests': ['tsvgm']}}, 18:28:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:28:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:28:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:28:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:28:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:28:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:28:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['damp', 'tps']}, 18:28:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'tests': ['damp', 'tps']}, 18:28:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['dromaeo_dom']}, 18:28:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:28:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['basic_compositor_video']}, 18:28:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:28:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['a11yr', 18:28:01 INFO - 'ts_paint', 18:28:01 INFO - 'tpaint', 18:28:01 INFO - 'sessionrestore', 18:28:01 INFO - 'sessionrestore_no_auto_restore', 18:28:01 INFO - 'tabpaint']}, 18:28:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:28:01 INFO - 'ts_paint', 18:28:01 INFO - 'tpaint', 18:28:01 INFO - 'sessionrestore', 18:28:01 INFO - 'sessionrestore_no_auto_restore', 18:28:01 INFO - 'tabpaint']}, 18:28:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:28:01 INFO - 'ts_paint', 18:28:01 INFO - 'tpaint', 18:28:01 INFO - 'sessionrestore', 18:28:01 INFO - 'sessionrestore_no_auto_restore', 18:28:01 INFO - 'tabpaint']}, 18:28:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:28:01 INFO - 'ts_paint', 18:28:01 INFO - 'tpaint', 18:28:01 INFO - 'sessionrestore', 18:28:01 INFO - 'sessionrestore_no_auto_restore', 18:28:01 INFO - 'tabpaint']}, 18:28:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['a11yr', 18:28:01 INFO - 'ts_paint', 18:28:01 INFO - 'tpaint', 18:28:01 INFO - 'sessionrestore', 18:28:01 INFO - 'sessionrestore_no_auto_restore', 18:28:01 INFO - 'tabpaint']}, 18:28:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['a11yr', 18:28:01 INFO - 'ts_paint', 18:28:01 INFO - 'tpaint', 18:28:01 INFO - 'sessionrestore', 18:28:01 INFO - 'sessionrestore_no_auto_restore', 18:28:01 INFO - 'tabpaint']}, 18:28:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['tsvgx', 18:28:01 INFO - 'tsvgr_opacity', 18:28:01 INFO - 'tart', 18:28:01 INFO - 'tscrollx', 18:28:01 INFO - 'cart']}, 18:28:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:28:01 INFO - 'tsvgr_opacity', 18:28:01 INFO - 'tart', 18:28:01 INFO - 'tscrollx', 18:28:01 INFO - 'cart']}, 18:28:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'talos_options': ['--disable-e10s'], 18:28:01 INFO - 'tests': ['tp5o']}, 18:28:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'tests': ['tp5o']}, 18:28:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'talos_options': ['--disable-e10s', 18:28:01 INFO - '--xperf_path', 18:28:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:28:01 INFO - 'tests': ['tp5n']}, 18:28:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:28:01 INFO - 'talos_options': ['--xperf_path', 18:28:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:28:01 INFO - 'tests': ['tp5n']}}, 18:28:01 INFO - 'talos.zip': {'path': '', 18:28:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:28:01 INFO - Running post-action listener: _resource_record_post_action 18:28:01 INFO - [mozharness: 2016-07-29 01:28:01.326036Z] Finished populate-webroot step (success) 18:28:01 INFO - [mozharness: 2016-07-29 01:28:01.326125Z] Running create-virtualenv step. 18:28:01 INFO - Running pre-action listener: _resource_record_pre_action 18:28:01 INFO - Running main action method: create_virtualenv 18:28:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:28:01 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 18:28:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:28:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:28:01 INFO - Using real prefix '/usr' 18:28:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:28:02 INFO - Installing distribute.............................................................................................................................................................................................done. 18:28:04 INFO - Installing pip.................done. 18:28:04 INFO - Return code: 0 18:28:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:04 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 18:28:04 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 18:28:04 INFO - Using env: {'DISPLAY': ':0', 18:28:04 INFO - 'HOME': '/home/cltbld', 18:28:04 INFO - 'LANG': 'en_US.UTF-8', 18:28:04 INFO - 'LANGUAGE': 'en_US:en', 18:28:04 INFO - 'LOGNAME': 'cltbld', 18:28:04 INFO - 'MAIL': '/var/mail/cltbld', 18:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:04 INFO - 'MOZ_NO_REMOTE': '1', 18:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:04 INFO - 'NO_EM_RESTART': '1', 18:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:04 INFO - 'PWD': '/builds/slave/test', 18:28:04 INFO - 'SHELL': '/bin/bash', 18:28:04 INFO - 'SHLVL': '1', 18:28:04 INFO - 'TERM': 'linux', 18:28:04 INFO - 'TMOUT': '86400', 18:28:04 INFO - 'USER': 'cltbld', 18:28:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:04 INFO - '_': '/tools/buildbot/bin/python'} 18:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:04 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 18:28:04 INFO - Cleaning up... 18:28:04 INFO - Return code: 0 18:28:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:04 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:04 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 18:28:04 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 18:28:04 INFO - Using env: {'DISPLAY': ':0', 18:28:04 INFO - 'HOME': '/home/cltbld', 18:28:04 INFO - 'LANG': 'en_US.UTF-8', 18:28:04 INFO - 'LANGUAGE': 'en_US:en', 18:28:04 INFO - 'LOGNAME': 'cltbld', 18:28:04 INFO - 'MAIL': '/var/mail/cltbld', 18:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:04 INFO - 'MOZ_NO_REMOTE': '1', 18:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:04 INFO - 'NO_EM_RESTART': '1', 18:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:04 INFO - 'PWD': '/builds/slave/test', 18:28:04 INFO - 'SHELL': '/bin/bash', 18:28:04 INFO - 'SHLVL': '1', 18:28:04 INFO - 'TERM': 'linux', 18:28:04 INFO - 'TMOUT': '86400', 18:28:04 INFO - 'USER': 'cltbld', 18:28:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:04 INFO - '_': '/tools/buildbot/bin/python'} 18:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:04 INFO - Downloading/unpacking psutil>=3.1.1 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 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 18:28:04 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 18:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:28:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:28:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:28:06 INFO - Installing collected packages: psutil 18:28:06 INFO - Running setup.py install for psutil 18:28:06 INFO - building 'psutil._psutil_linux' extension 18:28:06 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 18:28:06 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 18:28:06 INFO - building 'psutil._psutil_posix' extension 18:28:06 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 18:28:06 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 18:28:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:28:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:28:06 INFO - Successfully installed psutil 18:28:06 INFO - Cleaning up... 18:28:06 INFO - Return code: 0 18:28:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:06 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:06 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 18:28:06 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 18:28:06 INFO - Using env: {'DISPLAY': ':0', 18:28:06 INFO - 'HOME': '/home/cltbld', 18:28:06 INFO - 'LANG': 'en_US.UTF-8', 18:28:06 INFO - 'LANGUAGE': 'en_US:en', 18:28:06 INFO - 'LOGNAME': 'cltbld', 18:28:06 INFO - 'MAIL': '/var/mail/cltbld', 18:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:06 INFO - 'MOZ_NO_REMOTE': '1', 18:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:06 INFO - 'NO_EM_RESTART': '1', 18:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:06 INFO - 'PWD': '/builds/slave/test', 18:28:06 INFO - 'SHELL': '/bin/bash', 18:28:06 INFO - 'SHLVL': '1', 18:28:06 INFO - 'TERM': 'linux', 18:28:06 INFO - 'TMOUT': '86400', 18:28:06 INFO - 'USER': 'cltbld', 18:28:06 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:06 INFO - '_': '/tools/buildbot/bin/python'} 18:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:28:07 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 18:28:07 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 18:28:07 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 18:28:07 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 18:28:07 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 18:28:07 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 18:28:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:28:09 INFO - Installing collected packages: mozsystemmonitor 18:28:09 INFO - Running setup.py install for mozsystemmonitor 18:28:09 INFO - Successfully installed mozsystemmonitor 18:28:09 INFO - Cleaning up... 18:28:09 INFO - Return code: 0 18:28:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:09 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:09 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 18:28:09 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 18:28:09 INFO - Using env: {'DISPLAY': ':0', 18:28:09 INFO - 'HOME': '/home/cltbld', 18:28:09 INFO - 'LANG': 'en_US.UTF-8', 18:28:09 INFO - 'LANGUAGE': 'en_US:en', 18:28:09 INFO - 'LOGNAME': 'cltbld', 18:28:09 INFO - 'MAIL': '/var/mail/cltbld', 18:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:09 INFO - 'MOZ_NO_REMOTE': '1', 18:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:09 INFO - 'NO_EM_RESTART': '1', 18:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:09 INFO - 'PWD': '/builds/slave/test', 18:28:09 INFO - 'SHELL': '/bin/bash', 18:28:09 INFO - 'SHLVL': '1', 18:28:09 INFO - 'TERM': 'linux', 18:28:09 INFO - 'TMOUT': '86400', 18:28:09 INFO - 'USER': 'cltbld', 18:28:09 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:09 INFO - '_': '/tools/buildbot/bin/python'} 18:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:28:09 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 18:28:09 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 18:28:09 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 18:28:09 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 18:28:09 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 18:28:09 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 18:28:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:28:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:28:11 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 18:28:11 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 18:28:11 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 18:28:11 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 18:28:11 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 18:28:11 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 18:28:11 INFO - Downloading functools32-3.2.3-2.tar.gz 18:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:28:11 INFO - warning: no files found matching '*.txt' 18:28:11 INFO - no previously-included directories found matching 'build' 18:28:11 INFO - no previously-included directories found matching 'dist' 18:28:11 INFO - no previously-included directories found matching '.git*' 18:28:11 INFO - Installing collected packages: jsonschema, functools32 18:28:11 INFO - Running setup.py install for functools32 18:28:11 INFO - warning: no files found matching '*.txt' 18:28:11 INFO - no previously-included directories found matching 'build' 18:28:11 INFO - no previously-included directories found matching 'dist' 18:28:11 INFO - no previously-included directories found matching '.git*' 18:28:11 INFO - Successfully installed jsonschema functools32 18:28:11 INFO - Cleaning up... 18:28:11 INFO - Return code: 0 18:28:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:11 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:11 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 18:28:11 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 18:28:11 INFO - Using env: {'DISPLAY': ':0', 18:28:11 INFO - 'HOME': '/home/cltbld', 18:28:11 INFO - 'LANG': 'en_US.UTF-8', 18:28:11 INFO - 'LANGUAGE': 'en_US:en', 18:28:11 INFO - 'LOGNAME': 'cltbld', 18:28:11 INFO - 'MAIL': '/var/mail/cltbld', 18:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:11 INFO - 'MOZ_NO_REMOTE': '1', 18:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:11 INFO - 'NO_EM_RESTART': '1', 18:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:11 INFO - 'PWD': '/builds/slave/test', 18:28:11 INFO - 'SHELL': '/bin/bash', 18:28:11 INFO - 'SHLVL': '1', 18:28:11 INFO - 'TERM': 'linux', 18:28:11 INFO - 'TMOUT': '86400', 18:28:11 INFO - 'USER': 'cltbld', 18:28:11 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:11 INFO - '_': '/tools/buildbot/bin/python'} 18:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:28:11 INFO - Cleaning up... 18:28:12 INFO - Return code: 0 18:28:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:12 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:12 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 18:28:12 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 18:28:12 INFO - Using env: {'DISPLAY': ':0', 18:28:12 INFO - 'HOME': '/home/cltbld', 18:28:12 INFO - 'LANG': 'en_US.UTF-8', 18:28:12 INFO - 'LANGUAGE': 'en_US:en', 18:28:12 INFO - 'LOGNAME': 'cltbld', 18:28:12 INFO - 'MAIL': '/var/mail/cltbld', 18:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:12 INFO - 'MOZ_NO_REMOTE': '1', 18:28:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:12 INFO - 'NO_EM_RESTART': '1', 18:28:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:12 INFO - 'PWD': '/builds/slave/test', 18:28:12 INFO - 'SHELL': '/bin/bash', 18:28:12 INFO - 'SHLVL': '1', 18:28:12 INFO - 'TERM': 'linux', 18:28:12 INFO - 'TMOUT': '86400', 18:28:12 INFO - 'USER': 'cltbld', 18:28:12 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:12 INFO - '_': '/tools/buildbot/bin/python'} 18:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:12 INFO - Downloading/unpacking blobuploader==1.2.4 18:28:12 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 18:28:12 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 18:28:12 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 18:28:12 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 18:28:12 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 18:28:12 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 18:28:14 INFO - Downloading blobuploader-1.2.4.tar.gz 18:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:28:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:28:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:14 INFO - Downloading docopt-0.6.1.tar.gz 18:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:28:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:28:14 INFO - Running setup.py install for blobuploader 18:28:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:28:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:28:14 INFO - Running setup.py install for requests 18:28:15 INFO - Running setup.py install for docopt 18:28:15 INFO - Successfully installed blobuploader requests docopt 18:28:15 INFO - Cleaning up... 18:28:15 INFO - Return code: 0 18:28:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:15 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:15 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 18:28:15 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 18:28:15 INFO - Using env: {'DISPLAY': ':0', 18:28:15 INFO - 'HOME': '/home/cltbld', 18:28:15 INFO - 'LANG': 'en_US.UTF-8', 18:28:15 INFO - 'LANGUAGE': 'en_US:en', 18:28:15 INFO - 'LOGNAME': 'cltbld', 18:28:15 INFO - 'MAIL': '/var/mail/cltbld', 18:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:15 INFO - 'MOZ_NO_REMOTE': '1', 18:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:15 INFO - 'NO_EM_RESTART': '1', 18:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:15 INFO - 'PWD': '/builds/slave/test', 18:28:15 INFO - 'SHELL': '/bin/bash', 18:28:15 INFO - 'SHLVL': '1', 18:28:15 INFO - 'TERM': 'linux', 18:28:15 INFO - 'TMOUT': '86400', 18:28:15 INFO - 'USER': 'cltbld', 18:28:15 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:15 INFO - '_': '/tools/buildbot/bin/python'} 18:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:15 INFO - Running setup.py (path:/tmp/pip-ZcXth0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:15 INFO - Running setup.py (path:/tmp/pip-V35PYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:15 INFO - Running setup.py (path:/tmp/pip-ZCx_Qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:15 INFO - Running setup.py (path:/tmp/pip-sqEcGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:15 INFO - Running setup.py (path:/tmp/pip-Xn7Xxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:16 INFO - Running setup.py (path:/tmp/pip-LNMBYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:16 INFO - Running setup.py (path:/tmp/pip-tPrk3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:16 INFO - Running setup.py (path:/tmp/pip-MNXNfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:16 INFO - Running setup.py (path:/tmp/pip-gKaaRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:16 INFO - Running setup.py (path:/tmp/pip-2z2yTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:16 INFO - Running setup.py (path:/tmp/pip-rjvwQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:16 INFO - Running setup.py (path:/tmp/pip-mcoLhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:16 INFO - Running setup.py (path:/tmp/pip-k3GCYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:16 INFO - Running setup.py (path:/tmp/pip-_2UAox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:16 INFO - Running setup.py (path:/tmp/pip-hu_Ep2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:16 INFO - Running setup.py (path:/tmp/pip-ycpc5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:16 INFO - Running setup.py (path:/tmp/pip-1EoLiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:28:16 INFO - Running setup.py install for manifestparser 18:28:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Running setup.py install for mozcrash 18:28:17 INFO - Running setup.py install for mozdebug 18:28:17 INFO - Running setup.py install for mozdevice 18:28:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Running setup.py install for mozfile 18:28:17 INFO - Running setup.py install for mozhttpd 18:28:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Running setup.py install for mozinfo 18:28:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Running setup.py install for mozInstall 18:28:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Running setup.py install for mozleak 18:28:17 INFO - Running setup.py install for mozlog 18:28:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:28:17 INFO - Running setup.py install for moznetwork 18:28:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:28:18 INFO - Running setup.py install for mozprocess 18:28:18 INFO - Running setup.py install for mozprofile 18:28:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:28:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:28:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:28:18 INFO - Running setup.py install for mozrunner 18:28:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:28:18 INFO - Running setup.py install for mozscreenshot 18:28:18 INFO - Running setup.py install for moztest 18:28:18 INFO - Running setup.py install for mozversion 18:28:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:28:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:28:18 INFO - Cleaning up... 18:28:18 INFO - Return code: 0 18:28:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:18 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:18 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 18:28:18 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 18:28:18 INFO - Using env: {'DISPLAY': ':0', 18:28:18 INFO - 'HOME': '/home/cltbld', 18:28:18 INFO - 'LANG': 'en_US.UTF-8', 18:28:18 INFO - 'LANGUAGE': 'en_US:en', 18:28:18 INFO - 'LOGNAME': 'cltbld', 18:28:18 INFO - 'MAIL': '/var/mail/cltbld', 18:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:18 INFO - 'MOZ_NO_REMOTE': '1', 18:28:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:18 INFO - 'NO_EM_RESTART': '1', 18:28:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:18 INFO - 'PWD': '/builds/slave/test', 18:28:18 INFO - 'SHELL': '/bin/bash', 18:28:18 INFO - 'SHLVL': '1', 18:28:18 INFO - 'TERM': 'linux', 18:28:18 INFO - 'TMOUT': '86400', 18:28:18 INFO - 'USER': 'cltbld', 18:28:18 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:18 INFO - '_': '/tools/buildbot/bin/python'} 18:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:18 INFO - Running setup.py (path:/tmp/pip-2QUmR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:19 INFO - Running setup.py (path:/tmp/pip-uUNkfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:19 INFO - Running setup.py (path:/tmp/pip-kJh7Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:19 INFO - Running setup.py (path:/tmp/pip-ywu_dA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:19 INFO - Running setup.py (path:/tmp/pip-5zGSgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:19 INFO - Running setup.py (path:/tmp/pip-FwR9yB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:19 INFO - Running setup.py (path:/tmp/pip-asKuzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:19 INFO - Running setup.py (path:/tmp/pip-pnQaEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:19 INFO - Running setup.py (path:/tmp/pip-5rUYdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:19 INFO - Running setup.py (path:/tmp/pip-PkjuHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:19 INFO - Running setup.py (path:/tmp/pip-AEfiB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:19 INFO - Running setup.py (path:/tmp/pip-D5I5EM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:19 INFO - Running setup.py (path:/tmp/pip-wGBqOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:19 INFO - Running setup.py (path:/tmp/pip-FdOyIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:19 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)) 18:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:19 INFO - Running setup.py (path:/tmp/pip-pgo8Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:20 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)) 18:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:20 INFO - Running setup.py (path:/tmp/pip-MXsv9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:20 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)) 18:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:20 INFO - Running setup.py (path:/tmp/pip-X0hJB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:20 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)) 18:28:20 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)) 18:28:20 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)) 18:28:20 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)) 18:28:20 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)) 18:28:20 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)) 18:28:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18: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 18: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 18: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 18: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 18: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 18: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 18:28:22 INFO - Downloading blessings-1.6.tar.gz 18:28:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:22 INFO - Installing collected packages: blessings 18:28:22 INFO - Running setup.py install for blessings 18:28:22 INFO - Successfully installed blessings 18:28:22 INFO - Cleaning up... 18:28:22 INFO - Return code: 0 18:28:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:22 INFO - Reading from file tmpfile_stdout 18:28:22 INFO - Current package versions: 18:28:22 INFO - argparse == 1.2.1 18:28:22 INFO - blessings == 1.6 18:28:22 INFO - blobuploader == 1.2.4 18:28:22 INFO - docopt == 0.6.1 18:28:22 INFO - functools32 == 3.2.3-2 18:28:22 INFO - jsonschema == 2.5.1 18:28:22 INFO - manifestparser == 1.1 18:28:22 INFO - mozInstall == 1.12 18:28:22 INFO - mozcrash == 0.17 18:28:22 INFO - mozdebug == 0.1 18:28:22 INFO - mozdevice == 0.48 18:28:22 INFO - mozfile == 1.2 18:28:22 INFO - mozhttpd == 0.7 18:28:22 INFO - mozinfo == 0.9 18:28:22 INFO - mozleak == 0.1 18:28:22 INFO - mozlog == 3.3 18:28:22 INFO - moznetwork == 0.27 18:28:22 INFO - mozprocess == 0.23 18:28:22 INFO - mozprofile == 0.28 18:28:22 INFO - mozrunner == 6.12 18:28:22 INFO - mozscreenshot == 0.1 18:28:22 INFO - mozsystemmonitor == 0.3 18:28:22 INFO - moztest == 0.7 18:28:22 INFO - mozversion == 1.4 18:28:22 INFO - psutil == 3.1.1 18:28:22 INFO - requests == 1.2.3 18:28:22 INFO - wsgiref == 0.1.2 18:28:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:22 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 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18:28:22 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 18:28:22 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 18:28:22 INFO - Using env: {'DISPLAY': ':0', 18:28:22 INFO - 'HOME': '/home/cltbld', 18:28:22 INFO - 'LANG': 'en_US.UTF-8', 18:28:22 INFO - 'LANGUAGE': 'en_US:en', 18:28:22 INFO - 'LOGNAME': 'cltbld', 18:28:22 INFO - 'MAIL': '/var/mail/cltbld', 18:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:22 INFO - 'MOZ_NO_REMOTE': '1', 18:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:22 INFO - 'NO_EM_RESTART': '1', 18:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:22 INFO - 'PWD': '/builds/slave/test', 18:28:22 INFO - 'SHELL': '/bin/bash', 18:28:22 INFO - 'SHLVL': '1', 18:28:22 INFO - 'TERM': 'linux', 18:28:22 INFO - 'TMOUT': '86400', 18:28:22 INFO - 'USER': 'cltbld', 18:28:22 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:22 INFO - '_': '/tools/buildbot/bin/python'} 18:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 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)) 18:28:22 INFO - Cleaning up... 18:28:22 INFO - Return code: 0 18:28:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, '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': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', '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 18: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', 'jsonschema'] in /builds/slave/test/build 18: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 jsonschema 18:28:22 INFO - Using env: {'DISPLAY': ':0', 18:28:22 INFO - 'HOME': '/home/cltbld', 18:28:22 INFO - 'LANG': 'en_US.UTF-8', 18:28:22 INFO - 'LANGUAGE': 'en_US:en', 18:28:22 INFO - 'LOGNAME': 'cltbld', 18:28:22 INFO - 'MAIL': '/var/mail/cltbld', 18:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:22 INFO - 'MOZ_NO_REMOTE': '1', 18:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:22 INFO - 'NO_EM_RESTART': '1', 18:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:22 INFO - 'PWD': '/builds/slave/test', 18:28:22 INFO - 'SHELL': '/bin/bash', 18:28:22 INFO - 'SHLVL': '1', 18:28:22 INFO - 'TERM': 'linux', 18:28:22 INFO - 'TMOUT': '86400', 18:28:22 INFO - 'USER': 'cltbld', 18:28:22 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:22 INFO - '_': '/tools/buildbot/bin/python'} 18:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:28:22 INFO - Cleaning up... 18:28:22 INFO - Return code: 0 18:28:22 INFO - Running post-action listener: _resource_record_post_action 18:28:22 INFO - Running post-action listener: _start_resource_monitoring 18:28:22 INFO - Starting resource monitoring. 18:28:22 INFO - [mozharness: 2016-07-29 01:28:22.988150Z] Finished create-virtualenv step (success) 18:28:22 INFO - [mozharness: 2016-07-29 01:28:22.988630Z] Running install step. 18:28:22 INFO - Running pre-action listener: _resource_record_pre_action 18:28:22 INFO - Running main action method: install 18:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:23 INFO - Reading from file tmpfile_stdout 18:28:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:28:23 INFO - Reading from file tmpfile_stdout 18:28:23 INFO - Output received: 18:28:23 INFO - Usage: mozinstall [options] installer 18:28:23 INFO - Options: 18:28:23 INFO - -h, --help show this help message and exit 18:28:23 INFO - -d DEST, --destination=DEST 18:28:23 INFO - Directory to install application into. [default: 18:28:23 INFO - "/builds/slave/test"] 18:28:23 INFO - --app=APP Application being installed. [default: firefox] 18:28:23 INFO - mkdir: /builds/slave/test/build/application 18:28:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:28:39 INFO - Reading from file tmpfile_stdout 18:28:39 INFO - Output received: 18:28:39 INFO - /builds/slave/test/build/application/firefox/firefox 18:28:39 INFO - Running post-action listener: _resource_record_post_action 18:28:39 INFO - [mozharness: 2016-07-29 01:28:39.718995Z] Finished install step (success) 18:28:39 INFO - [mozharness: 2016-07-29 01:28:39.719337Z] Running run-tests step. 18:28:39 INFO - Running pre-action listener: _resource_record_pre_action 18:28:39 INFO - Running main action method: run_tests 18:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:28:39 INFO - Python 2.7.3 18:28:39 INFO - Return code: 0 18:28:39 INFO - grabbing minidump binary from tooltool 18:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:39 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 0x10863b0>, '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 0x10872b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:28:39 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 18:28:39 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 18:28:39 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 18:28:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:39 INFO - Return code: 0 18:28:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:28:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:28:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:28:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:28:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:28:39 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/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 18:28:39 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/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 18:28:39 INFO - Using env: {'DISPLAY': ':0', 18:28:39 INFO - 'HOME': '/home/cltbld', 18:28:39 INFO - 'LANG': 'en_US.UTF-8', 18:28:39 INFO - 'LANGUAGE': 'en_US:en', 18:28:39 INFO - 'LOGNAME': 'cltbld', 18:28:39 INFO - 'MAIL': '/var/mail/cltbld', 18:28:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:39 INFO - 'MOZ_NO_REMOTE': '1', 18:28:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:39 INFO - 'NO_EM_RESTART': '1', 18:28:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:39 INFO - 'PWD': '/builds/slave/test', 18:28:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:39 INFO - 'SHELL': '/bin/bash', 18:28:39 INFO - 'SHLVL': '1', 18:28:39 INFO - 'TERM': 'linux', 18:28:39 INFO - 'TMOUT': '86400', 18:28:39 INFO - 'USER': 'cltbld', 18:28:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038', 18:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:39 INFO - '_': '/tools/buildbot/bin/python'} 18:28:39 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/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 18:28:39 INFO - mozversion application_buildid: 20160728171331 18:28:39 INFO - mozversion application_changeset: b722fac1af4b88f9ed41f620b0eef2e851f04faa 18:28:39 INFO - mozversion application_display_name: Nightly 18:28:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:39 INFO - mozversion application_name: Firefox 18:28:39 INFO - mozversion application_remotingname: firefox 18:28:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:28:39 INFO - mozversion application_vendor: Mozilla 18:28:39 INFO - mozversion application_version: 50.0a1 18:28:39 INFO - mozversion platform_buildid: 20160728171331 18:28:39 INFO - mozversion platform_changeset: b722fac1af4b88f9ed41f620b0eef2e851f04faa 18:28:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:28:39 INFO - mozversion platform_version: 50.0a1 18:28:39 INFO - using testdate: 1469755719 18:28:39 INFO - actual date: 1469755719 18:28:39 INFO - starting webserver on 'localhost:54201' 18:28:39 INFO - SUITE-START | Running 2 tests 18:28:39 INFO - TEST-START | dromaeo_css 18:28:39 INFO - operating with platform_type : linux_ 18:28:39 INFO - Initialising browser for dromaeo_css test... 18:28:40 INFO - TEST-INFO | started process 29003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp26VxR9/profile http://localhost:54201/getInfo.html) 18:28:43 INFO - PROCESS | 29003 | JavaScript error: , line 0: uncaught exception: undefined 18:28:44 INFO - PROCESS | 29003 | __metrics Screen width/height:1600/1200 18:28:44 INFO - PROCESS | 29003 | colorDepth:24 18:28:44 INFO - PROCESS | 29003 | Browser inner width/height: 1024/697 18:28:44 INFO - PROCESS | 29003 | __metrics 18:28:44 INFO - PROCESS | 29003 | 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" 18:28:44 INFO - PROCESS | 29003 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:28:45 INFO - TEST-INFO | 29003: exit 0 18:28:45 INFO - Browser initialized. 18:28:45 INFO - Running cycle 1/1 for dromaeo_css test... 18:28:45 INFO - TEST-INFO | started process 29083 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp26VxR9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:57 INFO - PROCESS | 29083 | RSS: Main: 158982144 18:28:57 INFO - PROCESS | 29083 | 18:29:49 INFO - PROCESS | 29083 | 1469755789566 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] 18:32:01 INFO - PROCESS | 29083 | Cycle 1(1): loaded http://localhost:54201/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54201/tests/dromaeo/cssquery-ext.html) 18:32:02 INFO - PROCESS | 29083 | RSS: Main: 265678848 18:32:02 INFO - PROCESS | 29083 | 18:35:05 INFO - PROCESS | 29083 | Cycle 1(1): loaded http://localhost:54201/tests/dromaeo/cssquery-ext.html (next: http://localhost:54201/tests/dromaeo/cssquery-jquery.html) 18:35:05 INFO - PROCESS | 29083 | RSS: Main: 248688640 18:35:05 INFO - PROCESS | 29083 | 18:38:08 INFO - PROCESS | 29083 | Cycle 1(1): loaded http://localhost:54201/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54201/tests/dromaeo/cssquery-mootools.html) 18:38:08 INFO - PROCESS | 29083 | RSS: Main: 226127872 18:38:08 INFO - PROCESS | 29083 | 18:41:10 INFO - PROCESS | 29083 | Cycle 1(1): loaded http://localhost:54201/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54201/tests/dromaeo/cssquery-prototype.html) 18:41:11 INFO - PROCESS | 29083 | RSS: Main: 219586560 18:41:11 INFO - PROCESS | 29083 | 18:44:14 INFO - PROCESS | 29083 | Cycle 1(1): loaded http://localhost:54201/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54201/tests/dromaeo/cssquery-yui.html) 18:44:15 INFO - PROCESS | 29083 | RSS: Main: 190988288 18:44:15 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | Cycle 1(1): loaded http://localhost:54201/tests/dromaeo/cssquery-yui.html (next: http://localhost:54201/tests/dromaeo/cssquery-dojo.html) 18:47:18 INFO - PROCESS | 29083 | RSS: Main: 274120704 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | __start_tp_report 18:47:18 INFO - PROCESS | 29083 | _x_x_mozilla_page_load 18:47:18 INFO - PROCESS | 29083 | _x_x_mozilla_page_load_details 18:47:18 INFO - PROCESS | 29083 | |i|pagename|runs| 18:47:18 INFO - PROCESS | 29083 | |0;dojo.html;2631.7499837515434;2643.0617130918536;2667.30650030992;2670.5593577059785;2684.4631073785235;4612.861614151572;4691.366665500156;4735;4871.171900776868;4873.512648735129;4770.4275486941615;4772.832950846721;4799.856004319868;4815.085133824571;4820.734859582722;4298.075913678558;4299.140171965604;4364.89087772805;4416.845410410636;4496.303073023671;4393.736375817445;4446.3108218226225;4455.198064348416;4457.175422546823;4477.507474177838;4274.871753847389;4317.827286908519;4321.200577893069;4354.891127721832;4381.78091095444;4309.956900431018;4312.784360781949;4349;4399.406080179199;4461.955380446187;4344.326629372421;4365.934510982338;4375.321825117112;4386.517483076859;4400.251957167289;4627.791749371287;4702.858914232578;4710;4801;4806.399200099988;5064.746762661852;5117.33474648297;5149.30484384607;5157.071727089123;5157.638965272433;5145.9485405146215;5197.948020519784;5198.116320225533;5210.322658054467;5211.5830733541225;4553.385292985471;4749.525047495223;4775.188218002948;4795.592374648194;4835.758212089381;5229.267902493654;5243.842684719464;5284.841454756286;5288.81489147878;5307.6284660073425;5034.748262586856;5041.319421878073;5084.110280700863;5089.592832573385;5091.338126043591;5003.899922001539;5053.191489361684;5079.492050794891;5159.484051594885;5168.095583272912;5059.241113832954;5083.567896728746;5084.745762711849;5098.821541246039;5099.133147364957;5038.093143234253;5038.798448062036;5042.067217564762;5048.444671086178;5098.694078355311;4202.390653355246;4316.136772645484;4319;4320.049589090395;4374.912501750011;3705.814709264526;3801.6008319126645;3842.6733727632904;3888.8833334999995;3907.980460097738;4421.226285400042;4428.756418397019;4430.778461076933;4433.357163211316;4469.597736203757;5111.488851114858;5136.43499215086;5153.587712982952;5154.690718556899;5212.530872221519;4237.724547904377;4347;4375.453068366454;4375.7374557526655;4447.755373454491;4598.057398233354;4606.055758569485;4621.79201935907;4626.97686511572;4659.4175728034;4817.807287708452;4831.492693267227;4833.903321933612;4844.563989240844;4881.487443818348;4964.652474326763;5015.7492125392855;5017.774200160929;5094.465081166424;5094.923576146287;4751.548602882721;4768.237082066991;4780.856574302777;4782.6173906088125;4782.928256076231;4997.425296090996;5123.897522049613;5125.154349532286;5126.846194614168;5129.435762066244;5119.87200319995;5149.6910185389;5156.1492353761205;5157.355330583677;5158.968206358668;4999.925001125059;5015.1474249378425;5018.272350509229;5022.472640372855;5023.045621331935;4826.131296366688;4954.108260512998;4980.128477516348;4988.975055124701;5078.5175408336145;4880.389951256093;4922.237053256751;4940.75296235194;4999.250112483157;5003.299538064746;5009.448960614257;5053.469385714594;5058.089543882136;5064.18972964324;5098.796048158032;4714.339992400998;4774.618030557477;4823.131836269506;4839;4891.1685013548495 18:47:18 INFO - PROCESS | 29083 | |1;ext.html;576.2681394628821;582.8018473718936;593.7773334999375;598.4285007817534;602.6896148483529;26751;37490.625093749055;38802.4179637305;38982.415263771065;42542.149157016844;39767;40130.59869401305;40177.19645607086;40282.39576406356;40295.59704402955;7261.092363454568;7778.64996075178;7939.325157361631;7943.5631040292765;7946.483478573874;7237.7466788662405;7472.476926615139;7540.2082781308;9852.852207216898;10083.798324033514;10161.237907156992;10200.84698729516;10209.030142136486;10219.080282774548;10219.948900255526;10019.699409017705;10104.797904041952;10109.039641234145;10110.039546243168;10118.13995810357;9999.700008999742;10085.344452610558;10089;10092.495375231209;10096.798064038752;37628.43557346642;37827.4325885112;37866.8106659465;37877.24245515102;37915.81042094799;3064.9080527583983;3073.969260307413;3219.0342897130768;3356.96643033569;3363.6972672459597;28844;29082.709172908213;29311.41377172466;29482.705172948212;29602.111936641933;3343.498475228711;3408.352413041539;3498.3178280235365;3527.805970671612;3539.433690609516;259191;259785;260695;261287;262654;31479.37041259162;52266;52675.73662131742;52690.73654631779;52791;30905.381892362475;32837.34325313528;32987.835060824546;33034.17414564655;33037.83481082579;13577.456901724017;13758.65603359924;13763.449462021606;13767.38046787897;13779.586612401645;11632.418379081013;11766.88233117675;11772.764544709229;11773.470193841298;11794.52821887115;7319.817004574928;7651.196624354474;7881.133075361706;7903.012123484565;8034.839303213903;3085.629724433082;3094.7369473594995;3112.6264848217907;3128;3153.0540837748586;792.5442870349572;886.6409104312763;887.1572006593761;899.3344924755634;965.1603105298435;26625.467490650077;27987.72012279851;28329.71670283312;28474;28525.286867828054;935.9906400936039;940.6237504997919;952.1050212799946;971.718201721507;1009.5759780892043;14743.926280368529;14893.553193404216;15039.624009399633;15147.091174529563;15169.317380717894;396502;400504;401691;402046;402159;49145.75427122913;49522.25716614254;49598.25602615964;49621.25568116481;49679.75160124248;1063.9521221545049;1116.8659760828752;1147.3804145761362;1163.139276935045;1169.3101070368523;25633.743662563134;29196.963563764253;29404.705952940196;29514.852425737732;29626.407471850875;41322.38016429816;46017.30974035459;47101.29348059713;47169.76415117903;50792.746036269586;348600;352237;352591;352639;356432;24618;25779.613305800052;25781.613275801254;37862.810685946395;38414.23171536609;37419.193810058656;37661.811690942464;37788.244235114595;37864.62135378721;38040.42939355857;34590.65409345875;34912.30175396529;34923.825380872935;35761.64238357583;36117.458238125924;34642.307153857284;34683.82658086693;34855.082970436866;34914.47628285629;35175;34303.82848085744;34311.65688343085;34661.30677386488;35060;35315.823420882734;29973;31374.68625313809;31937.840310799227;31960.68039319577;32122.196945075626;24787.132450364872;24844.37889052788;24900.37749056288;24908.252752417455;25475 18:47:18 INFO - PROCESS | 29083 | |2;jquery.html;340.51373158634976;361.8552578968414;364.5898364340117;370.42029224264024;388.03379584833755;12761.361931903402;13563.186208827465;14850.999999999987;17174.39889603864;17178.39875604354;16242.0254784713;17017.914910425432;17367.565810854736;17367.91316043421;17404.21681024354;4916.188828843246;5102.005109003746;5103.209002604603;5106.234064890277;5108.77010534525;3755.2864955658474;5674.744636491368;5890.116482527612;5954.732037058321;6025.939740602582;5196.58427325813;5983.072623743326;5986.640801551921;5991.400859913995;5992.0712289595185;5435.347758268994;5579.804706835282;5583.944160558423;5588.105903055492;5590.077637189859;5292.020976119392;5330.280412144389;5364.409914909357;5390.946090539083;5400.648957817769;12377.876221237853;12453.75092498145;12512.74974500514;12578.182418142791;12586.433610487458;1695.4150817967782;1821.6538857617143;1939.1080103152567;2226.6214743493483;2311.3874823171873;9002.594883230271;9024.142706442877;9034.728958131267;9256.444613323154;9746.805063898682;1654.5367297156815;1669.5408762590264;1729.6367762769833;1730.723084306505;1741.8432341089235;70207;71587.64206178936;72879;74344.25655743427;79257;9172.633094676139;9385.249180065715;9487.33588648788;9566.90433095674;9952.850707239397;13425.798613020812;13444.327783610976;13446.462141514425;13456.86543134556;13475.663108422368;7364.189939106694;7424.071991001125;7531.510451820614;7549.660265288076;7567.508111972704;7073.363397294268;7230.891536626955;7365.705371785176;7435.888461673079;7569.621518924142;4229.788510574459;4235.2800023996;4240.279152544075;4256;4368.737875727466;1732.0819965418348;1741.8171092035407;1769.4956937272816;1778.8348631646293;1794.6410717856481;645.3224114679567;883.4743327719997;894.3560636341823;948.606328373731;949.2074118111316;16669.65423836857;16846.410375636795;17484.91257543704;17707.46877593674;17948.730769038215;834.870595057767;838.6645341863182;856.1010938514535;881.3962435731545;916.0061333453144;9394.530273486298;9847.80304393908;9970.252231082697;9986.149653244835;10055.849162262572;373927;375133;385125;385850;387127;78829.60585197152;80572.19427805647;81640.18359816444;82227;84103;883.0904168706114;890.8307421589877;892.8973168085623;898.60910503931;918.0048033980975;14199.219042952736;15271.083734975937;15358;15456.768148477557;15955.361785528448;38340.61659383371;39625.603743962194;39694.99999999884;40446.59553404428;40674.71228740245;48421.5157848417;49204;50905;56322;56794;7466.178720340867;7469.626518674153;7522.097348318237;7523.360514356122;7654.272844079804;8936.553172341486;11595.652130436101;11699;12104.394780261127;12197.817032744693;7030.664173807165;8099.271065604124;10043.69868903934;10074.546645400827;10627.09669678086;10468.319559228627;10500.369977801356;10669.839952400565;10725.839112413163;11135.721606959567;10233.84649230277;10285.177185824967;10400.739981500523;10609.416482093558;10722.946385268024;7418.590467811098;7498.550086994798;7505.737299194502;7780;7815.335696465864;9866.210703143875;9867.16129129032;9954;10054.94972525162;10196.41912065753 18:47:18 INFO - PROCESS | 29083 | |3;mootools.html;1361.918284902906;1391.3113009060514;1438.762604170312;1617.1025081079995;1687.0552490605262;7534.359579435748;8059.113497515269;8066.233707797757;8069.112397636262;8070.757877263692;6888.724451021953;7529.510581812178;7806.219378062191;7818.100918394386;7891.684332626703;2203.823694104468;2237.5860465813794;2239.3170083124724;2239.652853807654;2241.932742017742;2228.053077442081;2231.4421394651336;2263.309690544383;2264.920727774528;2270.5572413379396;2230.275160572812;2246.079107565898;2251.470904337479;2253.4479052632128;2262.072550254396;2118.5339225370394;2126.6384714598403;2142.0896119149456;2143.0570548958485;2147.795959383856;2069.596428696405;2071.357879057497;2073.523089689365;2073.927412540569;2074.6369385357652;8043.919560804376;8060.355171586259;8068.8386232275025;8137.389695772781;8145.307648849855;4490.483594386622;4608.33179189019;4609.216433206363;4610.308453731967;4617.30740388941;2801.1596521043807;2852.1728698677357;2925.13708456006;2936.8825246990095;2944.7055294470597;882.571952602987;895.749190226731;901.7520181950016;919.1451949686789;922.6678395777515;10914.672559823219;10921.726956826064;10964.287321324167;10983.231173817836;10994.065504432132;4050.1494686116243;4183.142455796616;4295.4201182839915;4312.719673221231;4320.416743739618;9393.624255029723;9415.246780257563;9424.95287523558;9425.90574094264;9431;4757.167495688241;4763.857084287477;4766.785494652749;4768.284757286435;4769.952300476951;3813.961860381416;4014.598540145962;4129.926219182974;4352.042550638855;4358.869233922987;3638.5633723952765;3641.8361173747244;3779;3796.1648437343742;3816.5229346331707;1175.7589694112687;1195.7429152732204;1202.9458674359676;1205.722683782735;1212.175720510044;674.6604308671995;822.7408366364576;829.1459796409587;838.9370797190235;843.4306842881031;5204.141316682781;5206.245094461282;5212.270282160501;5223.973880130575;5318.12250978592;813.763080118217;842.1746688245431;843.0178841649409;847.656699036891;855.9058503564603;3569.696575791035;3580.2660454606744;3646.252518233756;3684.060564556021;3849.2493963676816;9433.952830235941;9768.804623907483;9903.059209375098;9955.651552195639;9991.600335986479;9915;9921.652742154134;9938;9938;9940.850887236697;7371.262873712586;7444.330010299099;7528.209537998431;7573.583452910033;7589.924100759032;5339.332583427072;5356.517913387814;5363.704996225245;5396.9460305398015;5601.747921343631;9147.908520914973;9212.355135140477;9228.307876909124;9244.907550924405;9264.212541934012;9273.165415112673;9391.342606017786;9582.377145485521;9583.089606487372;9587.233021358135;5756.769729210785;6234.906476402767;6303.180586523723;6425.164728585254;6435.710393032231;7626.351760100453;7873.33076688465;7910.802229944297;7912.406569507195;7922.524648521107;6925.653717314215;6972.233054364117;7000.5799652019;7052.153741551047;7189.820254493679;6888.483363747639;6923.376896079176;6936.861262774817;6963.303669632996;6981.476389270927;6270.655113968775;6291.307956124914;6325.1144839721555;6379.6810159490915;6382.106505089384;4517.909641807211;4703.576678098988;4744.359511466046;4813.711177329372;4878.512148785093;4658.998315362078;4695.929561056519;4760.428748550057;4870.318155458168;4870.439899411613 18:47:18 INFO - PROCESS | 29083 | |4;prototype.html;287.896357311368;294.4155850636487;303.31450920918724;304.44590844662724;306.55702509873225;4919.9754001229985;4927.408710954682;4949.2328689053165;4987.625928055397;5013.2480127980825;4691.460482044566;4781.689190202635;4846.563809257166;4891.4374846892615;4965.801367945287;3899;3899.395593682984;3900.590438003997;3911.4915061042166;3917.334053210961;3893.766374017554;3898.5126859142606;3904.648581627653;3905.7265991380614;3910.2765988292113;3689.631036896316;3736.065983504124;3835.9808200959096;3860.8958117825714;3882.0877093883055;3835.961640383602;3848.1902916113127;3858.6913046956456;3859.8649047283493;3873.4577159197734;3407.6081250656243;3416.7266618670446;3416.8120753358558;3417.111550996734;3421.8289085545625;4666.226833803167;4791.6406269529925;4814.374131362935;4815.783289751969;4818.325434439182;4357.542458041891;4383.167198232358;4394.406755088086;4403.493598236212;4410.713303635253;5132.538071573576;5150.459201783796;5152.587792976553;5169.844904652867;5173.249878767595;3622.3660859349507;3720.534933133358;3727.273181729564;3728.9440658390145;3731.2724018816343;5359.954808812283;5361.705106219157;5372.785088596452;5385.35375754908;5393.78424863005;3784;3792.355299599075;3794.6964242860504;3798.905027374338;3806.238752249561;3857.247836671822;3863.8068096595057;3869.2648396804516;3869.4389313549377;3870;4259.680523960716;4330.39374487572;4372.562743725665;4383.715058521186;4385.188740083095;5121.334226550599;5132;5144.816692160824;5145.176771716508;5151.665141765773;1327.2766342343446;1335.9398827052805;1340.552203620088;1342.4697244588333;1343.3216225805907;847.5973912391564;853.9188777066169;887.2192470625808;911.9863202051974;916.8349697054595;4488.865334039984;4489.34904438861;4491.663125265618;4498.482674492409;4510.300903359985;5249.790008399621;5260.763265653055;5265.52610265078;5270.736463176826;5273.235380869753;4340.717853339523;4390.670699697535;4429.955700442954;4490.887727806766;4493.775311234491;2757.2279761666764;2773.4175823076976;2792.064658339438;2800.8599570021415;2806.761425278874;4939.357883475125;4941.45643979162;4943.728094954812;4956.479569645136;4977.262505621893;5204.713740744295;5396.1366181410785;5421.26812880264;5512.200730893998;5520.530754885799;5073.898522029539;5081.9745901271;5084.593232541314;5087.974560127176;5091.1854103343285;5404.891902161856;5414.945850541601;5423.511883930465;5430.212619170277;5432.266644003009;5159.406668233201;5224.320838290998;5244.591015839735;5272.6836389816735;5300.734963251899;5044.571211447068;5057.760848592118;5064.088464076502;5064.215046667772;5079.6698214615935;3755.75587586806;3760.8871733848027;3764.9269958061823;3764.981175094107;3784;5279.257844911247;5330.78867694517;5352.2337628583255;5362.45283490339;5384.380796208487;5213.191955246943;5221.947780522298;5226.216067589892;5227.686338819683;5231.869203269796;5059.924101138559;5195.19474481456;5205.501359347459;5212.869678258074;5219.660722053054;5083.949160508496;5122.718250496258;5128.743562821769;5130.87120833431;5133.178691409356;4977.651564390603;5054.21659642756;5189.481051894781;5199.870003249949;5205.531502164672;5023.34696489454;5025.6482046256415;5025.874353141201;5028.698278103326;5050.191969284897 18:47:18 INFO - PROCESS | 29083 | |5;yui.html;549.8323011481498;563.7942151114842;579.3192998227084;628.6291088257927;648.5233353485189;12139.21095128817;12843.229406235609;13278.668033299173;13313.933430332847;13318.13432126911;12319.322437265946;12515.374231288422;12527.561535346265;12544.749105017894;12622.305773182472;622.380731172484;726.4733068525329;744.6797876912925;746.2015643261713;746.3767753925479;642.299893116503;754.2608243920961;777.2383064596698;781.5701364249668;785.4933567848734;824.5094168969454;824.8927639406899;827.2141465607667;827.3091968206555;827.611022819274;654.7806484827588;825.3685930263323;825.5624519004937;826;826.2687521543418;469.6594968647703;486.1225487994169;487.79268810755286;498.39693970295986;508.20719677303526;12718.554850580185;12718.809217861739;12742.872571274262;12752.744945101138;12755.298458584773;11544.653660390202;11551.306921584732;11994.400279985966;12079.939600301943;12190.146689731722;11006.779864402668;11009.559617615285;11052.226344155912;11252.43737813106;11268.492917818636;3845.288621604984;3883.165119499293;3898.3372826619543;3924.803759811998;4008.899777505586;60170.398296016916;60319.09521357183;60337.39662603362;60851;65640.34359656437;8749.212570868525;8838.381313308135;8988.280937524982;9107.408018478778;9602.279829012712;16089.195540222941;16206;16481.34074637025;16488.422905198262;16488.83511164897;6449.677516124175;6486.124373209556;6568.376004279586;6570.967145164243;6614.933850661432;3599.8380072896784;3622.4023036198732;3632.5822530408814;3704.981475092607;3705.5923848376656;2657.7873770098345;2726.931826704348;2738.3017330580574;2750.5049091163382;2750.6286651302216;479.4989236248129;482.9975850120797;484.6292586171511;497.3882124986287;499.95000499949714;416.10329739411617;439.73835567837085;448.26484565312916;474.08738179005275;539.3285359727165;6191.752329906843;6673.933260667428;6813.863722725518;6885.345892140239;6969.337912898368;467.6048738815695;479.25715141530486;510.0691238489771;511.23059795008277;523.9947600524022;3232.6444091149956;3327.517509961042;3365.915852103717;3377.8648854046055;3543.1850674345046;56233.43766562282;57266.42733572611;71721;103088.48455757824;103195;51111.488885111416;58023.709881450326;80138.79791803128;85786.57106714426;85805.57097214473;65296;67743.32256677469;67922.66038669775;74537;81748.46012319;7394.593297368697;8014.679412823421;8022.27799498048;8241.629126689193;8279.62741676614;42318.36522452227;43566.78216608897;43998.78000609976;45936;46306.22162670116;58601;63885;64129;65124;68766.96849547361;10019.649312274036;10189.184865210913;10518.842217366593;10595.09941654968;10970.177236707119;11089.334639921308;11352.262102963281;11410.94294528522;11748.530058797553;12128.272303661808;10568.471576420994;10639.255252132276;10720.785584288427;10988.120950324115;11360.886391136313;10567.207459440418;10748.570057197938;11141.387223702774;11155.10759139283;11193.552257909592;10472.738181545523;10763.300385474919;10774.515146818569;10876.78246435051;11407.030402415887;8103.351731861555;8321.542315172723;8372.790680233044;8588.699395521127;8677.132286771272;8586.441881277697;8891.555422228992;8926.509042002492;9031.593578289125;9034.63861445561 18:47:18 INFO - PROCESS | 29083 | __end_tp_report 18:47:18 INFO - PROCESS | 29083 | __start_cc_report 18:47:18 INFO - PROCESS | 29083 | _x_x_mozilla_cycle_collect,1105 18:47:18 INFO - PROCESS | 29083 | __end_cc_report 18:47:18 INFO - PROCESS | 29083 | __startTimestamp1469756838372__endTimestamp 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | ------- Summary: start ------- 18:47:18 INFO - PROCESS | 29083 | Number of tests: 6 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | [#0] dojo.html Cycles:165 Average:4723.36 Median:4831.49 stddev:501.95 (10.4%) stddev-sans-first:475.92 18:47:18 INFO - PROCESS | 29083 | Values: 2631.7 2643.1 2667.3 2670.6 2684.5 4612.9 4691.4 4735.0 4871.2 4873.5 4770.4 4772.8 4799.9 4815.1 4820.7 4298.1 4299.1 4364.9 4416.8 4496.3 4393.7 4446.3 4455.2 4457.2 4477.5 4274.9 4317.8 4321.2 4354.9 4381.8 4310.0 4312.8 4349.0 4399.4 4462.0 4344.3 4365.9 4375.3 4386.5 4400.3 4627.8 4702.9 4710.0 4801.0 4806.4 5064.7 5117.3 5149.3 5157.1 5157.6 5145.9 5197.9 5198.1 5210.3 5211.6 4553.4 4749.5 4775.2 4795.6 4835.8 5229.3 5243.8 5284.8 5288.8 5307.6 5034.7 5041.3 5084.1 5089.6 5091.3 5003.9 5053.2 5079.5 5159.5 5168.1 5059.2 5083.6 5084.7 5098.8 5099.1 5038.1 5038.8 5042.1 5048.4 5098.7 4202.4 4316.1 4319.0 4320.0 4374.9 3705.8 3801.6 3842.7 3888.9 3908.0 4421.2 4428.8 4430.8 4433.4 4469.6 5111.5 5136.4 5153.6 5154.7 5212.5 4237.7 4347.0 4375.5 4375.7 4447.8 4598.1 4606.1 4621.8 4627.0 4659.4 4817.8 4831.5 4833.9 4844.6 4881.5 4964.7 5015.7 5017.8 5094.5 5094.9 4751.5 4768.2 4780.9 4782.6 4782.9 4997.4 5123.9 5125.2 5126.8 5129.4 5119.9 5149.7 5156.1 5157.4 5159.0 4999.9 5015.1 5018.3 5022.5 5023.0 4826.1 4954.1 4980.1 4989.0 5078.5 4880.4 4922.2 4940.8 4999.3 5003.3 5009.4 5053.5 5058.1 5064.2 5098.8 4714.3 4774.6 4823.1 4839.0 4891.2 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | [#1] ext.html Cycles:180 Average:48034.99 Median:34616.48 stddev:90528.90 (261.5%) stddev-sans-first:90712.73 18:47:18 INFO - PROCESS | 29083 | Values: 576.3 582.8 593.8 598.4 602.7 26751.0 37490.6 38802.4 38982.4 42542.1 39767.0 40130.6 40177.2 40282.4 40295.6 7261.1 7778.6 7939.3 7943.6 7946.5 7237.7 7472.5 7540.2 9852.9 10083.8 10161.2 10200.8 10209.0 10219.1 10219.9 10019.7 10104.8 10109.0 10110.0 10118.1 9999.7 10085.3 10089.0 10092.5 10096.8 37628.4 37827.4 37866.8 37877.2 37915.8 3064.9 3074.0 3219.0 3357.0 3363.7 28844.0 29082.7 29311.4 29482.7 29602.1 3343.5 3408.4 3498.3 3527.8 3539.4 259191.0 259785.0 260695.0 261287.0 262654.0 31479.4 52266.0 52675.7 52690.7 52791.0 30905.4 32837.3 32987.8 33034.2 33037.8 13577.5 13758.7 13763.4 13767.4 13779.6 11632.4 11766.9 11772.8 11773.5 11794.5 7319.8 7651.2 7881.1 7903.0 8034.8 3085.6 3094.7 3112.6 3128.0 3153.1 792.5 886.6 887.2 899.3 965.2 26625.5 27987.7 28329.7 28474.0 28525.3 936.0 940.6 952.1 971.7 1009.6 14743.9 14893.6 15039.6 15147.1 15169.3 396502.0 400504.0 401691.0 402046.0 402159.0 49145.8 49522.3 49598.3 49621.3 49679.8 1064.0 1116.9 1147.4 1163.1 1169.3 25633.7 29197.0 29404.7 29514.9 29626.4 41322.4 46017.3 47101.3 47169.8 50792.7 348600.0 352237.0 352591.0 352639.0 356432.0 24618.0 25779.6 25781.6 37862.8 38414.2 37419.2 37661.8 37788.2 37864.6 38040.4 34590.7 34912.3 34923.8 35761.6 36117.5 34642.3 34683.8 34855.1 34914.5 35175.0 34303.8 34311.7 34661.3 35060.0 35315.8 29973.0 31374.7 31937.8 31960.7 32122.2 24787.1 24844.4 24900.4 24908.3 25475.0 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | [#2] jquery.html Cycles:180 Average:24152.99 Median:5102.61 stddev:63320.70 (1240.9%) stddev-sans-first:63473.09 18:47:18 INFO - PROCESS | 29083 | Values: 340.5 361.9 364.6 370.4 388.0 12761.4 13563.2 14851.0 17174.4 17178.4 16242.0 17017.9 17367.6 17367.9 17404.2 4916.2 5102.0 5103.2 5106.2 5108.8 3755.3 5674.7 5890.1 5954.7 6025.9 5196.6 5983.1 5986.6 5991.4 5992.1 5435.3 5579.8 5583.9 5588.1 5590.1 5292.0 5330.3 5364.4 5390.9 5400.6 12377.9 12453.8 12512.7 12578.2 12586.4 1695.4 1821.7 1939.1 2226.6 2311.4 9002.6 9024.1 9034.7 9256.4 9746.8 1654.5 1669.5 1729.6 1730.7 1741.8 70207.0 71587.6 72879.0 74344.3 79257.0 9172.6 9385.2 9487.3 9566.9 9952.9 13425.8 13444.3 13446.5 13456.9 13475.7 7364.2 7424.1 7531.5 7549.7 7567.5 7073.4 7230.9 7365.7 7435.9 7569.6 4229.8 4235.3 4240.3 4256.0 4368.7 1732.1 1741.8 1769.5 1778.8 1794.6 645.3 883.5 894.4 948.6 949.2 16669.7 16846.4 17484.9 17707.5 17948.7 834.9 838.7 856.1 881.4 916.0 9394.5 9847.8 9970.3 9986.1 10055.8 373927.0 375133.0 385125.0 385850.0 387127.0 78829.6 80572.2 81640.2 82227.0 84103.0 883.1 890.8 892.9 898.6 918.0 14199.2 15271.1 15358.0 15456.8 15955.4 38340.6 39625.6 39695.0 40446.6 40674.7 48421.5 49204.0 50905.0 56322.0 56794.0 7466.2 7469.6 7522.1 7523.4 7654.3 8936.6 11595.7 11699.0 12104.4 12197.8 7030.7 8099.3 10043.7 10074.5 10627.1 10468.3 10500.4 10669.8 10725.8 11135.7 10233.8 10285.2 10400.7 10609.4 10722.9 7418.6 7498.6 7505.7 7780.0 7815.3 9866.2 9867.2 9954.0 10054.9 10196.4 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | [#3] mootools.html Cycles:180 Average:5241.05 Median:5347.93 stddev:2975.54 (55.6%) stddev-sans-first:2969.60 18:47:18 INFO - PROCESS | 29083 | Values: 1361.9 1391.3 1438.8 1617.1 1687.1 7534.4 8059.1 8066.2 8069.1 8070.8 6888.7 7529.5 7806.2 7818.1 7891.7 2203.8 2237.6 2239.3 2239.7 2241.9 2228.1 2231.4 2263.3 2264.9 2270.6 2230.3 2246.1 2251.5 2253.4 2262.1 2118.5 2126.6 2142.1 2143.1 2147.8 2069.6 2071.4 2073.5 2073.9 2074.6 8043.9 8060.4 8068.8 8137.4 8145.3 4490.5 4608.3 4609.2 4610.3 4617.3 2801.2 2852.2 2925.1 2936.9 2944.7 882.6 895.7 901.8 919.1 922.7 10914.7 10921.7 10964.3 10983.2 10994.1 4050.1 4183.1 4295.4 4312.7 4320.4 9393.6 9415.2 9425.0 9425.9 9431.0 4757.2 4763.9 4766.8 4768.3 4770.0 3814.0 4014.6 4129.9 4352.0 4358.9 3638.6 3641.8 3779.0 3796.2 3816.5 1175.8 1195.7 1202.9 1205.7 1212.2 674.7 822.7 829.1 838.9 843.4 5204.1 5206.2 5212.3 5224.0 5318.1 813.8 842.2 843.0 847.7 855.9 3569.7 3580.3 3646.3 3684.1 3849.2 9434.0 9768.8 9903.1 9955.7 9991.6 9915.0 9921.7 9938.0 9938.0 9940.9 7371.3 7444.3 7528.2 7573.6 7589.9 5339.3 5356.5 5363.7 5396.9 5601.7 9147.9 9212.4 9228.3 9244.9 9264.2 9273.2 9391.3 9582.4 9583.1 9587.2 5756.8 6234.9 6303.2 6425.2 6435.7 7626.4 7873.3 7910.8 7912.4 7922.5 6925.7 6972.2 7000.6 7052.2 7189.8 6888.5 6923.4 6936.9 6963.3 6981.5 6270.7 6291.3 6325.1 6379.7 6382.1 4517.9 4703.6 4744.4 4813.7 4878.5 4659.0 4695.9 4760.4 4870.3 4870.4 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | [#4] prototype.html Cycles:180 Average:4296.72 Median:4933.38 stddev:1245.76 (25.3%) stddev-sans-first:1212.37 18:47:18 INFO - PROCESS | 29083 | Values: 287.9 294.4 303.3 304.4 306.6 4920.0 4927.4 4949.2 4987.6 5013.2 4691.5 4781.7 4846.6 4891.4 4965.8 3899.0 3899.4 3900.6 3911.5 3917.3 3893.8 3898.5 3904.6 3905.7 3910.3 3689.6 3736.1 3836.0 3860.9 3882.1 3836.0 3848.2 3858.7 3859.9 3873.5 3407.6 3416.7 3416.8 3417.1 3421.8 4666.2 4791.6 4814.4 4815.8 4818.3 4357.5 4383.2 4394.4 4403.5 4410.7 5132.5 5150.5 5152.6 5169.8 5173.2 3622.4 3720.5 3727.3 3728.9 3731.3 5360.0 5361.7 5372.8 5385.4 5393.8 3784.0 3792.4 3794.7 3798.9 3806.2 3857.2 3863.8 3869.3 3869.4 3870.0 4259.7 4330.4 4372.6 4383.7 4385.2 5121.3 5132.0 5144.8 5145.2 5151.7 1327.3 1335.9 1340.6 1342.5 1343.3 847.6 853.9 887.2 912.0 916.8 4488.9 4489.3 4491.7 4498.5 4510.3 5249.8 5260.8 5265.5 5270.7 5273.2 4340.7 4390.7 4430.0 4490.9 4493.8 2757.2 2773.4 2792.1 2800.9 2806.8 4939.4 4941.5 4943.7 4956.5 4977.3 5204.7 5396.1 5421.3 5512.2 5520.5 5073.9 5082.0 5084.6 5088.0 5091.2 5404.9 5414.9 5423.5 5430.2 5432.3 5159.4 5224.3 5244.6 5272.7 5300.7 5044.6 5057.8 5064.1 5064.2 5079.7 3755.8 3760.9 3764.9 3765.0 3784.0 5279.3 5330.8 5352.2 5362.5 5384.4 5213.2 5221.9 5226.2 5227.7 5231.9 5059.9 5195.2 5205.5 5212.9 5219.7 5083.9 5122.7 5128.7 5130.9 5133.2 4977.7 5054.2 5189.5 5199.9 5205.5 5023.3 5025.6 5025.9 5028.7 5050.2 18:47:18 INFO - PROCESS | 29083 | 18:47:18 INFO - PROCESS | 29083 | [#5] yui.html Cycles:180 Average:16417.60 Median:483.81 stddev:23258.11 (4807.2%) stddev-sans-first:23292.84 18:47:18 INFO - PROCESS | 29083 | Values: 549.8 563.8 579.3 628.6 648.5 12139.2 12843.2 13278.7 13313.9 13318.1 12319.3 12515.4 12527.6 12544.7 12622.3 622.4 726.5 744.7 746.2 746.4 642.3 754.3 777.2 781.6 785.5 824.5 824.9 827.2 827.3 827.6 654.8 825.4 825.6 826.0 826.3 469.7 486.1 487.8 498.4 508.2 12718.6 12718.8 12742.9 12752.7 12755.3 11544.7 11551.3 11994.4 12079.9 12190.1 11006.8 11009.6 11052.2 11252.4 11268.5 3845.3 3883.2 3898.3 3924.8 4008.9 60170.4 60319.1 60337.4 60851.0 65640.3 8749.2 8838.4 8988.3 9107.4 9602.3 16089.2 16206.0 16481.3 16488.4 16488.8 6449.7 6486.1 6568.4 6571.0 6614.9 3599.8 3622.4 3632.6 3705.0 3705.6 2657.8 2726.9 2738.3 2750.5 2750.6 479.5 483.0 484.6 497.4 500.0 416.1 439.7 448.3 474.1 539.3 6191.8 6673.9 6813.9 6885.3 6969.3 467.6 479.3 510.1 511.2 524.0 3232.6 3327.5 3365.9 3377.9 3543.2 56233.4 57266.4 71721.0 103088.5 103195.0 51111.5 58023.7 80138.8 85786.6 85805.6 65296.0 67743.3 67922.7 74537.0 81748.5 7394.6 8014.7 8022.3 8241.6 8279.6 42318.4 43566.8 43998.8 45936.0 46306.2 58601.0 63885.0 64129.0 65124.0 68767.0 10019.6 10189.2 10518.8 10595.1 10970.2 11089.3 11352.3 11410.9 11748.5 12128.3 10568.5 10639.3 10720.8 10988.1 11360.9 10567.2 10748.6 11141.4 11155.1 11193.6 10472.7 10763.3 10774.5 10876.8 11407.0 8103.4 8321.5 8372.8 8588.7 8677.1 8586.4 8891.6 8926.5 9031.6 9034.6 18:47:18 INFO - PROCESS | 29083 | -------- Summary: end -------- 18:47:18 INFO - PROCESS | 29083 | 18:47:19 INFO - TEST-INFO | 29083: exit 0 18:47:19 INFO - TEST-OK | dromaeo_css | took 1119186ms 18:47:19 INFO - TEST-START | kraken 18:47:19 INFO - operating with platform_type : linux_ 18:47:19 INFO - Initialising browser for kraken test... 18:47:19 INFO - TEST-INFO | started process 29266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuN4Aae/profile http://localhost:54201/getInfo.html) 18:47:22 INFO - PROCESS | 29266 | JavaScript error: , line 0: uncaught exception: undefined 18:47:23 INFO - PROCESS | 29266 | __metrics Screen width/height:1600/1200 18:47:23 INFO - PROCESS | 29266 | colorDepth:24 18:47:23 INFO - PROCESS | 29266 | Browser inner width/height: 1024/697 18:47:23 INFO - PROCESS | 29266 | __metrics 18:47:23 INFO - PROCESS | 29266 | 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" 18:47:23 INFO - PROCESS | 29266 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:47:24 INFO - TEST-INFO | 29266: exit 0 18:47:24 INFO - Browser initialized. 18:47:24 INFO - Running cycle 1/1 for kraken test... 18:47:24 INFO - TEST-INFO | started process 29345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuN4Aae/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:36 INFO - PROCESS | 29345 | RSS: Main: 159248384 18:47:36 INFO - PROCESS | 29345 | 18:47:39 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54201/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:40 INFO - PROCESS | 29345 | RSS: Main: 215629824 18:47:40 INFO - PROCESS | 29345 | 18:47:43 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54201/tests/kraken/driver.html?testName=audio-dft) 18:47:43 INFO - PROCESS | 29345 | RSS: Main: 192765952 18:47:43 INFO - PROCESS | 29345 | 18:47:47 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54201/tests/kraken/driver.html?testName=audio-fft) 18:47:47 INFO - PROCESS | 29345 | RSS: Main: 271564800 18:47:47 INFO - PROCESS | 29345 | 18:47:49 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54201/tests/kraken/driver.html?testName=audio-oscillator) 18:47:50 INFO - PROCESS | 29345 | RSS: Main: 181436416 18:47:50 INFO - PROCESS | 29345 | 18:47:52 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54201/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:53 INFO - PROCESS | 29345 | RSS: Main: 186982400 18:47:53 INFO - PROCESS | 29345 | 18:47:56 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54201/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:57 INFO - PROCESS | 29345 | RSS: Main: 223260672 18:47:57 INFO - PROCESS | 29345 | 18:48:00 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54201/tests/kraken/driver.html?testName=imaging-desaturate) 18:48:01 INFO - PROCESS | 29345 | RSS: Main: 216817664 18:48:01 INFO - PROCESS | 29345 | 18:48:04 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54201/tests/kraken/driver.html?testName=json-parse-financial) 18:48:04 INFO - PROCESS | 29345 | RSS: Main: 220717056 18:48:04 INFO - PROCESS | 29345 | 18:48:06 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54201/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:48:07 INFO - PROCESS | 29345 | RSS: Main: 186552320 18:48:07 INFO - PROCESS | 29345 | 18:48:09 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:48:09 INFO - PROCESS | 29345 | RSS: Main: 191324160 18:48:09 INFO - PROCESS | 29345 | 18:48:11 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:48:12 INFO - PROCESS | 29345 | RSS: Main: 196046848 18:48:12 INFO - PROCESS | 29345 | 18:48:15 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:48:15 INFO - PROCESS | 29345 | RSS: Main: 190427136 18:48:15 INFO - PROCESS | 29345 | 18:48:18 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:48:19 INFO - PROCESS | 29345 | RSS: Main: 193994752 18:48:19 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | Cycle 1(1): loaded http://localhost:54201/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54201/tests/kraken/driver.html?testName=ai-astar) 18:48:21 INFO - PROCESS | 29345 | RSS: Main: 244424704 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | __start_tp_report 18:48:21 INFO - PROCESS | 29345 | _x_x_mozilla_page_load 18:48:21 INFO - PROCESS | 29345 | _x_x_mozilla_page_load_details 18:48:21 INFO - PROCESS | 29345 | |i|pagename|runs| 18:48:21 INFO - PROCESS | 29345 | |0;ai-astar;104;115;115;114;113;113;112;113;115;113 18:48:21 INFO - PROCESS | 29345 | |1;audio-beat-detection;147;124;125;186;122;125;148;123;162;128 18:48:21 INFO - PROCESS | 29345 | |2;audio-dft;161;162;161;160;161;160;159;161;161;163 18:48:21 INFO - PROCESS | 29345 | |3;audio-fft;76;78;78;125;77;77;75;75;100;76 18:48:21 INFO - PROCESS | 29345 | |4;audio-oscillator;85;86;124;85;85;87;126;84;85;126 18:48:21 INFO - PROCESS | 29345 | |5;imaging-gaussian-blur;117;118;118;117;117;116;116;117;117;117 18:48:21 INFO - PROCESS | 29345 | |6;imaging-darkroom;147;146;147;147;147;146;146;147;146;147 18:48:21 INFO - PROCESS | 29345 | |7;imaging-desaturate;96;95;95;96;96;95;95;95;95;94 18:48:21 INFO - PROCESS | 29345 | |8;json-parse-financial;64;63;63;63;64;65;63;64;63;64 18:48:21 INFO - PROCESS | 29345 | |9;json-stringify-tinderbox;66;66;65;66;67;66;66;66;65;66 18:48:21 INFO - PROCESS | 29345 | |10;stanford-crypto-aes;86;79;78;80;79;80;79;85;80;81 18:48:21 INFO - PROCESS | 29345 | |11;stanford-crypto-ccm;130;139;142;132;132;129;128;127;179;134 18:48:21 INFO - PROCESS | 29345 | |12;stanford-crypto-pbkdf2;164;167;165;164;164;168;168;171;165;170 18:48:21 INFO - PROCESS | 29345 | |13;stanford-crypto-sha256-iterative;67;67;68;73;69;71;68;68;67;67 18:48:21 INFO - PROCESS | 29345 | __end_tp_report 18:48:21 INFO - PROCESS | 29345 | __start_cc_report 18:48:21 INFO - PROCESS | 29345 | _x_x_mozilla_cycle_collect,1979 18:48:21 INFO - PROCESS | 29345 | __end_cc_report 18:48:21 INFO - PROCESS | 29345 | __startTimestamp1469756901452__endTimestamp 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | ------- Summary: start ------- 18:48:21 INFO - PROCESS | 29345 | Number of tests: 14 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:3.23 (2.8%) stddev-sans-first:1.12 18:48:21 INFO - PROCESS | 29345 | Values: 104.0 115.0 115.0 114.0 113.0 113.0 112.0 113.0 115.0 113.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:137.50 stddev:21.51 (15.6%) stddev-sans-first:22.62 18:48:21 INFO - PROCESS | 29345 | Values: 147.0 124.0 125.0 186.0 122.0 125.0 148.0 123.0 162.0 128.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 18:48:21 INFO - PROCESS | 29345 | Values: 161.0 162.0 161.0 160.0 161.0 160.0 159.0 161.0 161.0 163.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#3] audio-fft Cycles:10 Average:83.70 Median:76.50 stddev:16.32 (21.3%) stddev-sans-first:17.07 18:48:21 INFO - PROCESS | 29345 | Values: 76.0 78.0 78.0 125.0 77.0 77.0 75.0 75.0 100.0 76.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:85.00 stddev:19.37 (22.8%) stddev-sans-first:20.02 18:48:21 INFO - PROCESS | 29345 | Values: 85.0 86.0 124.0 85.0 85.0 87.0 126.0 84.0 85.0 126.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 18:48:21 INFO - PROCESS | 29345 | Values: 117.0 118.0 118.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:48:21 INFO - PROCESS | 29345 | Values: 147.0 146.0 147.0 147.0 147.0 146.0 146.0 147.0 146.0 147.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 18:48:21 INFO - PROCESS | 29345 | Values: 96.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 94.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 18:48:21 INFO - PROCESS | 29345 | Values: 64.0 63.0 63.0 63.0 64.0 65.0 63.0 64.0 63.0 64.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 18:48:21 INFO - PROCESS | 29345 | Values: 66.0 66.0 65.0 66.0 67.0 66.0 66.0 66.0 65.0 66.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.00 stddev:2.67 (3.3%) stddev-sans-first:2.03 18:48:21 INFO - PROCESS | 29345 | Values: 86.0 79.0 78.0 80.0 79.0 80.0 79.0 85.0 80.0 81.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:133.00 stddev:15.44 (11.6%) stddev-sans-first:16.16 18:48:21 INFO - PROCESS | 29345 | Values: 130.0 139.0 142.0 132.0 132.0 129.0 128.0 127.0 179.0 134.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.50 stddev:2.59 (1.5%) stddev-sans-first:2.57 18:48:21 INFO - PROCESS | 29345 | Values: 164.0 167.0 165.0 164.0 164.0 168.0 168.0 171.0 165.0 170.0 18:48:21 INFO - PROCESS | 29345 | 18:48:21 INFO - PROCESS | 29345 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.01 (3.0%) stddev-sans-first:2.06 18:48:21 INFO - PROCESS | 29345 | Values: 67.0 67.0 68.0 73.0 69.0 71.0 68.0 68.0 67.0 67.0 18:48:21 INFO - PROCESS | 29345 | -------- Summary: end -------- 18:48:21 INFO - PROCESS | 29345 | 18:48:22 INFO - TEST-INFO | 29345: exit 0 18:48:22 INFO - TEST-OK | kraken | took 63394ms 18:48:22 INFO - SUITE-END | took 1182s 18:48:22 INFO - Completed test suite (00:19:42) 18:48:22 INFO - javascript benchmark 18:48:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2631.7499837515434, 2643.0617130918536, 2667.30650030992, 2670.5593577059785, 2684.4631073785235, 4612.861614151572, 4691.366665500156, 4735.0, 4871.171900776868, 4873.512648735129, 4770.4275486941615, 4772.832950846721, 4799.856004319868, 4815.085133824571, 4820.734859582722, 4298.075913678558, 4299.140171965604, 4364.89087772805, 4416.845410410636, 4496.303073023671, 4393.736375817445, 4446.3108218226225, 4455.198064348416, 4457.175422546823, 4477.507474177838, 4274.871753847389, 4317.827286908519, 4321.200577893069, 4354.891127721832, 4381.78091095444, 4309.956900431018, 4312.784360781949, 4349.0, 4399.406080179199, 4461.955380446187, 4344.326629372421, 4365.934510982338, 4375.321825117112, 4386.517483076859, 4400.251957167289, 4627.791749371287, 4702.858914232578, 4710.0, 4801.0, 4806.399200099988, 5064.746762661852, 5117.33474648297, 5149.30484384607, 5157.071727089123, 5157.638965272433, 5145.9485405146215, 5197.948020519784, 5198.116320225533, 5210.322658054467, 5211.5830733541225, 4553.385292985471, 4749.525047495223, 4775.188218002948, 4795.592374648194, 4835.758212089381, 5229.267902493654, 5243.842684719464, 5284.841454756286, 5288.81489147878, 5307.6284660073425, 5034.748262586856, 5041.319421878073, 5084.110280700863, 5089.592832573385, 5091.338126043591, 5003.899922001539, 5053.191489361684, 5079.492050794891, 5159.484051594885, 5168.095583272912, 5059.241113832954, 5083.567896728746, 5084.745762711849, 5098.821541246039, 5099.133147364957, 5038.093143234253, 5038.798448062036, 5042.067217564762, 5048.444671086178, 5098.694078355311, 4202.390653355246, 4316.136772645484, 4319.0, 4320.049589090395, 4374.912501750011, 3705.814709264526, 3801.6008319126645, 3842.6733727632904, 3888.8833334999995, 3907.980460097738, 4421.226285400042, 4428.756418397019, 4430.778461076933, 4433.357163211316, 4469.597736203757, 5111.488851114858, 5136.43499215086, 5153.587712982952, 5154.690718556899, 5212.530872221519, 4237.724547904377, 4347.0, 4375.453068366454, 4375.7374557526655, 4447.755373454491, 4598.057398233354, 4606.055758569485, 4621.79201935907, 4626.97686511572, 4659.4175728034, 4817.807287708452, 4831.492693267227, 4833.903321933612, 4844.563989240844, 4881.487443818348, 4964.652474326763, 5015.7492125392855, 5017.774200160929, 5094.465081166424, 5094.923576146287, 4751.548602882721, 4768.237082066991, 4780.856574302777, 4782.6173906088125, 4782.928256076231, 4997.425296090996, 5123.897522049613, 5125.154349532286, 5126.846194614168, 5129.435762066244, 5119.87200319995, 5149.6910185389, 5156.1492353761205, 5157.355330583677, 5158.968206358668, 4999.925001125059, 5015.1474249378425, 5018.272350509229, 5022.472640372855, 5023.045621331935, 4826.131296366688, 4954.108260512998, 4980.128477516348, 4988.975055124701, 5078.5175408336145, 4880.389951256093, 4922.237053256751, 4940.75296235194, 4999.250112483157, 5003.299538064746, 5009.448960614257, 5053.469385714594, 5058.089543882136, 5064.18972964324, 5098.796048158032, 4714.339992400998, 4774.618030557477, 4823.131836269506, 4839.0, 4891.1685013548495], "value": 4691.023799389262, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.2681394628821, 582.8018473718936, 593.7773334999375, 598.4285007817534, 602.6896148483529, 26751.0, 37490.625093749055, 38802.4179637305, 38982.415263771065, 42542.149157016844, 39767.0, 40130.59869401305, 40177.19645607086, 40282.39576406356, 40295.59704402955, 7261.092363454568, 7778.64996075178, 7939.325157361631, 7943.5631040292765, 7946.483478573874, 7237.7466788662405, 7472.476926615139, 7540.2082781308, 9852.852207216898, 10083.798324033514, 10161.237907156992, 10200.84698729516, 10209.030142136486, 10219.080282774548, 10219.948900255526, 10019.699409017705, 10104.797904041952, 10109.039641234145, 10110.039546243168, 10118.13995810357, 9999.700008999742, 10085.344452610558, 10089.0, 10092.495375231209, 10096.798064038752, 37628.43557346642, 37827.4325885112, 37866.8106659465, 37877.24245515102, 37915.81042094799, 3064.9080527583983, 3073.969260307413, 3219.0342897130768, 3356.96643033569, 3363.6972672459597, 28844.0, 29082.709172908213, 29311.41377172466, 29482.705172948212, 29602.111936641933, 3343.498475228711, 3408.352413041539, 3498.3178280235365, 3527.805970671612, 3539.433690609516, 259191.0, 259785.0, 260695.0, 261287.0, 262654.0, 31479.37041259162, 52266.0, 52675.73662131742, 52690.73654631779, 52791.0, 30905.381892362475, 32837.34325313528, 32987.835060824546, 33034.17414564655, 33037.83481082579, 13577.456901724017, 13758.65603359924, 13763.449462021606, 13767.38046787897, 13779.586612401645, 11632.418379081013, 11766.88233117675, 11772.764544709229, 11773.470193841298, 11794.52821887115, 7319.817004574928, 7651.196624354474, 7881.133075361706, 7903.012123484565, 8034.839303213903, 3085.629724433082, 3094.7369473594995, 3112.6264848217907, 3128.0, 3153.0540837748586, 792.5442870349572, 886.6409104312763, 887.1572006593761, 899.3344924755634, 965.1603105298435, 26625.467490650077, 27987.72012279851, 28329.71670283312, 28474.0, 28525.286867828054, 935.9906400936039, 940.6237504997919, 952.1050212799946, 971.718201721507, 1009.5759780892043, 14743.926280368529, 14893.553193404216, 15039.624009399633, 15147.091174529563, 15169.317380717894, 396502.0, 400504.0, 401691.0, 402046.0, 402159.0, 49145.75427122913, 49522.25716614254, 49598.25602615964, 49621.25568116481, 49679.75160124248, 1063.9521221545049, 1116.8659760828752, 1147.3804145761362, 1163.139276935045, 1169.3101070368523, 25633.743662563134, 29196.963563764253, 29404.705952940196, 29514.852425737732, 29626.407471850875, 41322.38016429816, 46017.30974035459, 47101.29348059713, 47169.76415117903, 50792.746036269586, 348600.0, 352237.0, 352591.0, 352639.0, 356432.0, 24618.0, 25779.613305800052, 25781.613275801254, 37862.810685946395, 38414.23171536609, 37419.193810058656, 37661.811690942464, 37788.244235114595, 37864.62135378721, 38040.42939355857, 34590.65409345875, 34912.30175396529, 34923.825380872935, 35761.64238357583, 36117.458238125924, 34642.307153857284, 34683.82658086693, 34855.082970436866, 34914.47628285629, 35175.0, 34303.82848085744, 34311.65688343085, 34661.30677386488, 35060.0, 35315.823420882734, 29973.0, 31374.68625313809, 31937.840310799227, 31960.68039319577, 32122.196945075626, 24787.132450364872, 24844.37889052788, 24900.37749056288, 24908.252752417455, 25475.0], "value": 16934.830789895856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.51373158634976, 361.8552578968414, 364.5898364340117, 370.42029224264024, 388.03379584833755, 12761.361931903402, 13563.186208827465, 14850.999999999987, 17174.39889603864, 17178.39875604354, 16242.0254784713, 17017.914910425432, 17367.565810854736, 17367.91316043421, 17404.21681024354, 4916.188828843246, 5102.005109003746, 5103.209002604603, 5106.234064890277, 5108.77010534525, 3755.2864955658474, 5674.744636491368, 5890.116482527612, 5954.732037058321, 6025.939740602582, 5196.58427325813, 5983.072623743326, 5986.640801551921, 5991.400859913995, 5992.0712289595185, 5435.347758268994, 5579.804706835282, 5583.944160558423, 5588.105903055492, 5590.077637189859, 5292.020976119392, 5330.280412144389, 5364.409914909357, 5390.946090539083, 5400.648957817769, 12377.876221237853, 12453.75092498145, 12512.74974500514, 12578.182418142791, 12586.433610487458, 1695.4150817967782, 1821.6538857617143, 1939.1080103152567, 2226.6214743493483, 2311.3874823171873, 9002.594883230271, 9024.142706442877, 9034.728958131267, 9256.444613323154, 9746.805063898682, 1654.5367297156815, 1669.5408762590264, 1729.6367762769833, 1730.723084306505, 1741.8432341089235, 70207.0, 71587.64206178936, 72879.0, 74344.25655743427, 79257.0, 9172.633094676139, 9385.249180065715, 9487.33588648788, 9566.90433095674, 9952.850707239397, 13425.798613020812, 13444.327783610976, 13446.462141514425, 13456.86543134556, 13475.663108422368, 7364.189939106694, 7424.071991001125, 7531.510451820614, 7549.660265288076, 7567.508111972704, 7073.363397294268, 7230.891536626955, 7365.705371785176, 7435.888461673079, 7569.621518924142, 4229.788510574459, 4235.2800023996, 4240.279152544075, 4256.0, 4368.737875727466, 1732.0819965418348, 1741.8171092035407, 1769.4956937272816, 1778.8348631646293, 1794.6410717856481, 645.3224114679567, 883.4743327719997, 894.3560636341823, 948.606328373731, 949.2074118111316, 16669.65423836857, 16846.410375636795, 17484.91257543704, 17707.46877593674, 17948.730769038215, 834.870595057767, 838.6645341863182, 856.1010938514535, 881.3962435731545, 916.0061333453144, 9394.530273486298, 9847.80304393908, 9970.252231082697, 9986.149653244835, 10055.849162262572, 373927.0, 375133.0, 385125.0, 385850.0, 387127.0, 78829.60585197152, 80572.19427805647, 81640.18359816444, 82227.0, 84103.0, 883.0904168706114, 890.8307421589877, 892.8973168085623, 898.60910503931, 918.0048033980975, 14199.219042952736, 15271.083734975937, 15358.0, 15456.768148477557, 15955.361785528448, 38340.61659383371, 39625.603743962194, 39694.99999999884, 40446.59553404428, 40674.71228740245, 48421.5157848417, 49204.0, 50905.0, 56322.0, 56794.0, 7466.178720340867, 7469.626518674153, 7522.097348318237, 7523.360514356122, 7654.272844079804, 8936.553172341486, 11595.652130436101, 11699.0, 12104.394780261127, 12197.817032744693, 7030.664173807165, 8099.271065604124, 10043.69868903934, 10074.546645400827, 10627.09669678086, 10468.319559228627, 10500.369977801356, 10669.839952400565, 10725.839112413163, 11135.721606959567, 10233.84649230277, 10285.177185824967, 10400.739981500523, 10609.416482093558, 10722.946385268024, 7418.590467811098, 7498.550086994798, 7505.737299194502, 7780.0, 7815.335696465864, 9866.210703143875, 9867.16129129032, 9954.0, 10054.94972525162, 10196.41912065753], "value": 8105.667206530166, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.918284902906, 1391.3113009060514, 1438.762604170312, 1617.1025081079995, 1687.0552490605262, 7534.359579435748, 8059.113497515269, 8066.233707797757, 8069.112397636262, 8070.757877263692, 6888.724451021953, 7529.510581812178, 7806.219378062191, 7818.100918394386, 7891.684332626703, 2203.823694104468, 2237.5860465813794, 2239.3170083124724, 2239.652853807654, 2241.932742017742, 2228.053077442081, 2231.4421394651336, 2263.309690544383, 2264.920727774528, 2270.5572413379396, 2230.275160572812, 2246.079107565898, 2251.470904337479, 2253.4479052632128, 2262.072550254396, 2118.5339225370394, 2126.6384714598403, 2142.0896119149456, 2143.0570548958485, 2147.795959383856, 2069.596428696405, 2071.357879057497, 2073.523089689365, 2073.927412540569, 2074.6369385357652, 8043.919560804376, 8060.355171586259, 8068.8386232275025, 8137.389695772781, 8145.307648849855, 4490.483594386622, 4608.33179189019, 4609.216433206363, 4610.308453731967, 4617.30740388941, 2801.1596521043807, 2852.1728698677357, 2925.13708456006, 2936.8825246990095, 2944.7055294470597, 882.571952602987, 895.749190226731, 901.7520181950016, 919.1451949686789, 922.6678395777515, 10914.672559823219, 10921.726956826064, 10964.287321324167, 10983.231173817836, 10994.065504432132, 4050.1494686116243, 4183.142455796616, 4295.4201182839915, 4312.719673221231, 4320.416743739618, 9393.624255029723, 9415.246780257563, 9424.95287523558, 9425.90574094264, 9431.0, 4757.167495688241, 4763.857084287477, 4766.785494652749, 4768.284757286435, 4769.952300476951, 3813.961860381416, 4014.598540145962, 4129.926219182974, 4352.042550638855, 4358.869233922987, 3638.5633723952765, 3641.8361173747244, 3779.0, 3796.1648437343742, 3816.5229346331707, 1175.7589694112687, 1195.7429152732204, 1202.9458674359676, 1205.722683782735, 1212.175720510044, 674.6604308671995, 822.7408366364576, 829.1459796409587, 838.9370797190235, 843.4306842881031, 5204.141316682781, 5206.245094461282, 5212.270282160501, 5223.973880130575, 5318.12250978592, 813.763080118217, 842.1746688245431, 843.0178841649409, 847.656699036891, 855.9058503564603, 3569.696575791035, 3580.2660454606744, 3646.252518233756, 3684.060564556021, 3849.2493963676816, 9433.952830235941, 9768.804623907483, 9903.059209375098, 9955.651552195639, 9991.600335986479, 9915.0, 9921.652742154134, 9938.0, 9938.0, 9940.850887236697, 7371.262873712586, 7444.330010299099, 7528.209537998431, 7573.583452910033, 7589.924100759032, 5339.332583427072, 5356.517913387814, 5363.704996225245, 5396.9460305398015, 5601.747921343631, 9147.908520914973, 9212.355135140477, 9228.307876909124, 9244.907550924405, 9264.212541934012, 9273.165415112673, 9391.342606017786, 9582.377145485521, 9583.089606487372, 9587.233021358135, 5756.769729210785, 6234.906476402767, 6303.180586523723, 6425.164728585254, 6435.710393032231, 7626.351760100453, 7873.33076688465, 7910.802229944297, 7912.406569507195, 7922.524648521107, 6925.653717314215, 6972.233054364117, 7000.5799652019, 7052.153741551047, 7189.820254493679, 6888.483363747639, 6923.376896079176, 6936.861262774817, 6963.303669632996, 6981.476389270927, 6270.655113968775, 6291.307956124914, 6325.1144839721555, 6379.6810159490915, 6382.106505089384, 4517.909641807211, 4703.576678098988, 4744.359511466046, 4813.711177329372, 4878.512148785093, 4658.998315362078, 4695.929561056519, 4760.428748550057, 4870.318155458168, 4870.439899411613], "value": 4193.547303941038, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.896357311368, 294.4155850636487, 303.31450920918724, 304.44590844662724, 306.55702509873225, 4919.9754001229985, 4927.408710954682, 4949.2328689053165, 4987.625928055397, 5013.2480127980825, 4691.460482044566, 4781.689190202635, 4846.563809257166, 4891.4374846892615, 4965.801367945287, 3899.0, 3899.395593682984, 3900.590438003997, 3911.4915061042166, 3917.334053210961, 3893.766374017554, 3898.5126859142606, 3904.648581627653, 3905.7265991380614, 3910.2765988292113, 3689.631036896316, 3736.065983504124, 3835.9808200959096, 3860.8958117825714, 3882.0877093883055, 3835.961640383602, 3848.1902916113127, 3858.6913046956456, 3859.8649047283493, 3873.4577159197734, 3407.6081250656243, 3416.7266618670446, 3416.8120753358558, 3417.111550996734, 3421.8289085545625, 4666.226833803167, 4791.6406269529925, 4814.374131362935, 4815.783289751969, 4818.325434439182, 4357.542458041891, 4383.167198232358, 4394.406755088086, 4403.493598236212, 4410.713303635253, 5132.538071573576, 5150.459201783796, 5152.587792976553, 5169.844904652867, 5173.249878767595, 3622.3660859349507, 3720.534933133358, 3727.273181729564, 3728.9440658390145, 3731.2724018816343, 5359.954808812283, 5361.705106219157, 5372.785088596452, 5385.35375754908, 5393.78424863005, 3784.0, 3792.355299599075, 3794.6964242860504, 3798.905027374338, 3806.238752249561, 3857.247836671822, 3863.8068096595057, 3869.2648396804516, 3869.4389313549377, 3870.0, 4259.680523960716, 4330.39374487572, 4372.562743725665, 4383.715058521186, 4385.188740083095, 5121.334226550599, 5132.0, 5144.816692160824, 5145.176771716508, 5151.665141765773, 1327.2766342343446, 1335.9398827052805, 1340.552203620088, 1342.4697244588333, 1343.3216225805907, 847.5973912391564, 853.9188777066169, 887.2192470625808, 911.9863202051974, 916.8349697054595, 4488.865334039984, 4489.34904438861, 4491.663125265618, 4498.482674492409, 4510.300903359985, 5249.790008399621, 5260.763265653055, 5265.52610265078, 5270.736463176826, 5273.235380869753, 4340.717853339523, 4390.670699697535, 4429.955700442954, 4490.887727806766, 4493.775311234491, 2757.2279761666764, 2773.4175823076976, 2792.064658339438, 2800.8599570021415, 2806.761425278874, 4939.357883475125, 4941.45643979162, 4943.728094954812, 4956.479569645136, 4977.262505621893, 5204.713740744295, 5396.1366181410785, 5421.26812880264, 5512.200730893998, 5520.530754885799, 5073.898522029539, 5081.9745901271, 5084.593232541314, 5087.974560127176, 5091.1854103343285, 5404.891902161856, 5414.945850541601, 5423.511883930465, 5430.212619170277, 5432.266644003009, 5159.406668233201, 5224.320838290998, 5244.591015839735, 5272.6836389816735, 5300.734963251899, 5044.571211447068, 5057.760848592118, 5064.088464076502, 5064.215046667772, 5079.6698214615935, 3755.75587586806, 3760.8871733848027, 3764.9269958061823, 3764.981175094107, 3784.0, 5279.257844911247, 5330.78867694517, 5352.2337628583255, 5362.45283490339, 5384.380796208487, 5213.191955246943, 5221.947780522298, 5226.216067589892, 5227.686338819683, 5231.869203269796, 5059.924101138559, 5195.19474481456, 5205.501359347459, 5212.869678258074, 5219.660722053054, 5083.949160508496, 5122.718250496258, 5128.743562821769, 5130.87120833431, 5133.178691409356, 4977.651564390603, 5054.21659642756, 5189.481051894781, 5199.870003249949, 5205.531502164672, 5023.34696489454, 5025.6482046256415, 5025.874353141201, 5028.698278103326, 5050.191969284897], "value": 3899.2998000815214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.8323011481498, 563.7942151114842, 579.3192998227084, 628.6291088257927, 648.5233353485189, 12139.21095128817, 12843.229406235609, 13278.668033299173, 13313.933430332847, 13318.13432126911, 12319.322437265946, 12515.374231288422, 12527.561535346265, 12544.749105017894, 12622.305773182472, 622.380731172484, 726.4733068525329, 744.6797876912925, 746.2015643261713, 746.3767753925479, 642.299893116503, 754.2608243920961, 777.2383064596698, 781.5701364249668, 785.4933567848734, 824.5094168969454, 824.8927639406899, 827.2141465607667, 827.3091968206555, 827.611022819274, 654.7806484827588, 825.3685930263323, 825.5624519004937, 826.0, 826.2687521543418, 469.6594968647703, 486.1225487994169, 487.79268810755286, 498.39693970295986, 508.20719677303526, 12718.554850580185, 12718.809217861739, 12742.872571274262, 12752.744945101138, 12755.298458584773, 11544.653660390202, 11551.306921584732, 11994.400279985966, 12079.939600301943, 12190.146689731722, 11006.779864402668, 11009.559617615285, 11052.226344155912, 11252.43737813106, 11268.492917818636, 3845.288621604984, 3883.165119499293, 3898.3372826619543, 3924.803759811998, 4008.899777505586, 60170.398296016916, 60319.09521357183, 60337.39662603362, 60851.0, 65640.34359656437, 8749.212570868525, 8838.381313308135, 8988.280937524982, 9107.408018478778, 9602.279829012712, 16089.195540222941, 16206.0, 16481.34074637025, 16488.422905198262, 16488.83511164897, 6449.677516124175, 6486.124373209556, 6568.376004279586, 6570.967145164243, 6614.933850661432, 3599.8380072896784, 3622.4023036198732, 3632.5822530408814, 3704.981475092607, 3705.5923848376656, 2657.7873770098345, 2726.931826704348, 2738.3017330580574, 2750.5049091163382, 2750.6286651302216, 479.4989236248129, 482.9975850120797, 484.6292586171511, 497.3882124986287, 499.95000499949714, 416.10329739411617, 439.73835567837085, 448.26484565312916, 474.08738179005275, 539.3285359727165, 6191.752329906843, 6673.933260667428, 6813.863722725518, 6885.345892140239, 6969.337912898368, 467.6048738815695, 479.25715141530486, 510.0691238489771, 511.23059795008277, 523.9947600524022, 3232.6444091149956, 3327.517509961042, 3365.915852103717, 3377.8648854046055, 3543.1850674345046, 56233.43766562282, 57266.42733572611, 71721.0, 103088.48455757824, 103195.0, 51111.488885111416, 58023.709881450326, 80138.79791803128, 85786.57106714426, 85805.57097214473, 65296.0, 67743.32256677469, 67922.66038669775, 74537.0, 81748.46012319, 7394.593297368697, 8014.679412823421, 8022.27799498048, 8241.629126689193, 8279.62741676614, 42318.36522452227, 43566.78216608897, 43998.78000609976, 45936.0, 46306.22162670116, 58601.0, 63885.0, 64129.0, 65124.0, 68766.96849547361, 10019.649312274036, 10189.184865210913, 10518.842217366593, 10595.09941654968, 10970.177236707119, 11089.334639921308, 11352.262102963281, 11410.94294528522, 11748.530058797553, 12128.272303661808, 10568.471576420994, 10639.255252132276, 10720.785584288427, 10988.120950324115, 11360.886391136313, 10567.207459440418, 10748.570057197938, 11141.387223702774, 11155.10759139283, 11193.552257909592, 10472.738181545523, 10763.300385474919, 10774.515146818569, 10876.78246435051, 11407.030402415887, 8103.351731861555, 8321.542315172723, 8372.790680233044, 8588.699395521127, 8677.132286771272, 8586.441881277697, 8891.555422228992, 8926.509042002492, 9031.593578289125, 9034.63861445561], "value": 6092.64215945651, "unit": "score"}], "name": "dromaeo_css", "value": 6327.170646866282, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 114.0, 113.0, 113.0, 112.0, 113.0, 115.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 125.0, 186.0, 122.0, 125.0, 148.0, 123.0, 162.0, 128.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 161.0, 160.0, 159.0, 161.0, 161.0, 163.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 78.0, 125.0, 77.0, 77.0, 75.0, 75.0, 100.0, 76.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 124.0, 85.0, 85.0, 87.0, 126.0, 84.0, 85.0, 126.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 65.0, 63.0, 64.0, 63.0, 64.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 78.0, 80.0, 79.0, 80.0, 79.0, 85.0, 80.0, 81.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 139.0, 142.0, 132.0, 132.0, 129.0, 128.0, 127.0, 179.0, 134.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 165.0, 164.0, 164.0, 168.0, 168.0, 171.0, 165.0, 170.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 73.0, 69.0, 71.0, 68.0, 68.0, 67.0, 67.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1534.9, "alertThreshold": 2.0}]} 18:48:22 INFO - Return code: 0 18:48:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:48:22 INFO - # TBPL SUCCESS # 18:48:22 INFO - Running post-action listener: _resource_record_post_action 18:48:22 INFO - [mozharness: 2016-07-29 01:48:22.759894Z] Finished run-tests step (success) 18:48:22 INFO - Running post-run listener: _resource_record_post_run 18:48:22 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 12288; Write bytes: 540450816; Read time: 320; Write time: 429264 18:48:22 INFO - TinderboxPrint: CPU usage
13.0% 18:48:22 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 320 18:48:22 INFO - TinderboxPrint: I/O write bytes / time
540,450,816 / 429,264 18:48:22 INFO - TinderboxPrint: CPU idle
8,328.3 (87.0%) 18:48:22 INFO - TinderboxPrint: CPU user
1,198.3 (12.5%) 18:48:22 INFO - TinderboxPrint: Swap in / out
0 / 0 18:48:22 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 257167360; Read time: 120; Write time: 251428 18:48:22 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 8192; Write bytes: 283283456; Read time: 200; Write time: 177836 18:48:23 INFO - Running post-run listener: _upload_blobber_files 18:48:23 INFO - Blob upload gear active. 18:48:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:48:23 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 18:48:23 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'] 18:48:23 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 18:48:23 INFO - (blobuploader) - INFO - Open directory for files ... 18:48:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:48:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:48:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:27 INFO - (blobuploader) - INFO - Done attempting. 18:48:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:48:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:48:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:28 INFO - (blobuploader) - INFO - Done attempting. 18:48:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:48:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:48:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:29 INFO - (blobuploader) - INFO - Done attempting. 18:48:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:48:29 INFO - Return code: 0 18:48:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:48:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:48:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e689752ad80833985cb287c383a94fc4f0262a95749b0e3b8941ffb6714c4a98d24d91bbb07fb82d2bfb987d9ecc4a47a172436c9a8dc3c264877275b1127ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cab76e8f0c0bcbad85ea9a4cc0d656a6108fdc71d8d8eb8218562cd78f9ede807b85ccab4ea2680662e0c5de6fb83d71a3b9fe43aca5f09532e5939d9b797436", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1fdd4c630550609b1d36bd0edf140abc7f2c1f44aae2e3a6444922c2e6e591609a6891db695cac661738939a6bdc33095668e3233eda68f854ad5da706a578ad"} 18:48:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:48:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:48:29 INFO - Contents: 18:48:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e689752ad80833985cb287c383a94fc4f0262a95749b0e3b8941ffb6714c4a98d24d91bbb07fb82d2bfb987d9ecc4a47a172436c9a8dc3c264877275b1127ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cab76e8f0c0bcbad85ea9a4cc0d656a6108fdc71d8d8eb8218562cd78f9ede807b85ccab4ea2680662e0c5de6fb83d71a3b9fe43aca5f09532e5939d9b797436", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1fdd4c630550609b1d36bd0edf140abc7f2c1f44aae2e3a6444922c2e6e591609a6891db695cac661738939a6bdc33095668e3233eda68f854ad5da706a578ad"} 18:48:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:48:29 INFO - Copying logs to upload dir... 18:48:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:48:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.090593 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-28 18:48:28.425167) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 18:48:28.428852) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038 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/e689752ad80833985cb287c383a94fc4f0262a95749b0e3b8941ffb6714c4a98d24d91bbb07fb82d2bfb987d9ecc4a47a172436c9a8dc3c264877275b1127ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cab76e8f0c0bcbad85ea9a4cc0d656a6108fdc71d8d8eb8218562cd78f9ede807b85ccab4ea2680662e0c5de6fb83d71a3b9fe43aca5f09532e5939d9b797436", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1fdd4c630550609b1d36bd0edf140abc7f2c1f44aae2e3a6444922c2e6e591609a6891db695cac661738939a6bdc33095668e3233eda68f854ad5da706a578ad"} build_url:https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/fzxC_WGNRQCTzdx0bAji3g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e689752ad80833985cb287c383a94fc4f0262a95749b0e3b8941ffb6714c4a98d24d91bbb07fb82d2bfb987d9ecc4a47a172436c9a8dc3c264877275b1127ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cab76e8f0c0bcbad85ea9a4cc0d656a6108fdc71d8d8eb8218562cd78f9ede807b85ccab4ea2680662e0c5de6fb83d71a3b9fe43aca5f09532e5939d9b797436", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1fdd4c630550609b1d36bd0edf140abc7f2c1f44aae2e3a6444922c2e6e591609a6891db695cac661738939a6bdc33095668e3233eda68f854ad5da706a578ad"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-28 18:48:28.454697) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 18:48:28.455056) ========= 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=3a3620fb28aea1604ab609ef000001d2-1469755669.387086-1500877038 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 18:48:28.537693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 18:48:28.538024) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-28 18:48:28.538329) ========= ========= Total master_lag: 0.15 =========