builder: larch_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-017 starttime: 1464722957.78 results: success (0) buildid: 20160531111747 builduid: b59ecb76b9974a20abb331f19affea40 revision: ea5c88b997faa8be5ef6a936304a908fd0292894 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.778422) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.778908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.779192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.814036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.814543) ========= 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=0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-31 12:29:17-- 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% 226M=0s 2016-05-31 12:29:18 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.973534) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:17.973850) ========= 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=0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.097578 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:18.082750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 12:29:18.083076) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev ea5c88b997faa8be5ef6a936304a908fd0292894 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev ea5c88b997faa8be5ef6a936304a908fd0292894 --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=0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-31 12:29:18,186 truncating revision to first 12 chars 2016-05-31 12:29:18,186 Setting DEBUG logging. 2016-05-31 12:29:18,187 attempt 1/10 2016-05-31 12:29:18,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/ea5c88b997fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 12:29:18,506 unpacking tar archive at: larch-ea5c88b997fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.090701 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 12:29:19.187458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:19.187771) ========= script_repo_revision: ea5c88b997faa8be5ef6a936304a908fd0292894 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:19.188207) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:19.188510) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 12:29:19.203859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-31 12:29:19.204142) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:29:19 INFO - MultiFileLogger online at 20160531 12:29:19 in /builds/slave/test 12:29:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 12:29:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:29:19 INFO - {'append_to_log': False, 12:29:19 INFO - 'base_work_dir': '/builds/slave/test', 12:29:19 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 12:29:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:29:19 INFO - 'branch': 'Larch-Non-PGO', 12:29:19 INFO - 'buildbot_json_path': 'buildprops.json', 12:29:19 INFO - 'config_files': ('talos/linux_config.py',), 12:29:19 INFO - 'default_actions': ('clobber', 12:29:19 INFO - 'read-buildbot-config', 12:29:19 INFO - 'download-and-extract', 12:29:19 INFO - 'populate-webroot', 12:29:19 INFO - 'create-virtualenv', 12:29:19 INFO - 'install', 12:29:19 INFO - 'run-tests'), 12:29:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:29:19 INFO - 'download_minidump_stackwalk': True, 12:29:19 INFO - 'download_symbols': 'ondemand', 12:29:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:29:19 INFO - 'tooltool.py': '/tools/tooltool.py', 12:29:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:29:19 INFO - '/tools/misc-python/virtualenv.py')}, 12:29:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:29:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:29:19 INFO - 'installer_path': 'installer.exe', 12:29:19 INFO - 'log_level': 'info', 12:29:19 INFO - 'log_name': 'talos', 12:29:19 INFO - 'log_to_console': True, 12:29:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:29:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:29:19 INFO - 'opt_config_files': (), 12:29:19 INFO - 'pip_index': False, 12:29:19 INFO - 'sps_profile': False, 12:29:19 INFO - 'sps_profile_interval': 0, 12:29:19 INFO - 'suite': 'dromaeojs-e10s', 12:29:19 INFO - 'system_bits': '32', 12:29:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:29:19 INFO - 'title': 'talos-linux64-ix-017', 12:29:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:29:19 INFO - 'use_talos_json': True, 12:29:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:29:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:29:19 INFO - 'work_dir': 'build'} 12:29:19 INFO - ##### 12:29:19 INFO - ##### Running clobber step. 12:29:19 INFO - ##### 12:29:19 INFO - Running pre-action listener: _resource_record_pre_action 12:29:19 INFO - Running main action method: clobber 12:29:19 INFO - rmtree: /builds/slave/test/build 12:29:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:29:21 INFO - Running post-action listener: _resource_record_post_action 12:29:21 INFO - ##### 12:29:21 INFO - ##### Finished clobber step (success) 12:29:21 INFO - ##### 12:29:21 INFO - ##### 12:29:21 INFO - ##### Running read-buildbot-config step. 12:29:21 INFO - ##### 12:29:21 INFO - Running pre-action listener: _resource_record_pre_action 12:29:21 INFO - Running main action method: read_buildbot_config 12:29:21 INFO - Using buildbot properties: 12:29:21 INFO - { 12:29:21 INFO - "project": "", 12:29:21 INFO - "product": "firefox", 12:29:21 INFO - "script_repo_revision": "production", 12:29:21 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 12:29:21 INFO - "repository": "", 12:29:21 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs-e10s", 12:29:21 INFO - "buildid": "20160531111747", 12:29:21 INFO - "pgo_build": "False", 12:29:21 INFO - "basedir": "/builds/slave/test", 12:29:21 INFO - "buildnumber": 6, 12:29:21 INFO - "slavename": "talos-linux64-ix-017", 12:29:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:29:21 INFO - "platform": "ubuntu64_hw", 12:29:21 INFO - "scheduler": "tests-larch-linux64-talos", 12:29:21 INFO - "branch": "larch", 12:29:21 INFO - "repo_path": "projects/larch", 12:29:21 INFO - "revision": "ea5c88b997faa8be5ef6a936304a908fd0292894", 12:29:21 INFO - "stage_platform": "linux64", 12:29:21 INFO - "builduid": "b59ecb76b9974a20abb331f19affea40", 12:29:21 INFO - "slavebuilddir": "test" 12:29:21 INFO - } 12:29:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 12:29:21 INFO - Running post-action listener: _resource_record_post_action 12:29:21 INFO - ##### 12:29:21 INFO - ##### Finished read-buildbot-config step (success) 12:29:21 INFO - ##### 12:29:21 INFO - ##### 12:29:21 INFO - ##### Running download-and-extract step. 12:29:21 INFO - ##### 12:29:21 INFO - Running pre-action listener: _resource_record_pre_action 12:29:21 INFO - Running main action method: download_and_extract 12:29:21 INFO - mkdir: /builds/slave/test/build/tests 12:29:21 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')]} 12:29:21 INFO - https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:29:24 INFO - Downloaded 1361 bytes. 12:29:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:29:24 INFO - Using the following test package requirements: 12:29:24 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:29:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:29:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:29:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'jsshell-linux-x86_64.zip'], 12:29:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:29:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:29:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:29:24 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:29:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:29:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:29:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:29:24 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')]} 12:29:24 INFO - https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:29:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:29:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:29:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:29:28 INFO - Downloaded 22840344 bytes. 12:29:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:29:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:29:29 INFO - Return code: 0 12:29:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:29:29 INFO - https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:29:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:29:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:29:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:29:33 INFO - Downloaded 22840344 bytes. 12:29:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:29:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:29:34 INFO - Return code: 0 12:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:34 INFO - https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:29:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:29:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:29:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:29:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:29:35 INFO - Downloaded 10834063 bytes. 12:29:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:29:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:29:36 INFO - Return code: 0 12:29:36 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')]} 12:29:36 INFO - https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:29:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:29:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:29:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:29:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:29:38 INFO - Downloaded 55132151 bytes. 12:29:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:29:38 INFO - mkdir: /builds/slave/test/properties 12:29:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:29:38 INFO - Writing to file /builds/slave/test/properties/build_url 12:29:38 INFO - Contents: 12:29:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:29:39 INFO - Running post-action listener: _resource_record_post_action 12:29:39 INFO - Running post-action listener: set_extra_try_arguments 12:29:39 INFO - ##### 12:29:39 INFO - ##### Finished download-and-extract step (success) 12:29:39 INFO - ##### 12:29:39 INFO - ##### 12:29:39 INFO - ##### Running populate-webroot step. 12:29:39 INFO - ##### 12:29:39 INFO - Running pre-action listener: _resource_record_pre_action 12:29:39 INFO - Running main action method: populate_webroot 12:29:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:29:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:29:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:29:39 INFO - '--tppagecycles', 12:29:39 INFO - '7'], 12:29:39 INFO - 'tests': ['tsvgm']}}, 12:29:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:29:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:29:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:29:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:29:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['damp', 'tps']}, 12:29:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'tests': ['damp', 'tps']}, 12:29:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['dromaeo_dom']}, 12:29:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:29:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['a11yr', 12:29:39 INFO - 'ts_paint', 12:29:39 INFO - 'tpaint', 12:29:39 INFO - 'sessionrestore', 12:29:39 INFO - 'sessionrestore_no_auto_restore', 12:29:39 INFO - 'tabpaint']}, 12:29:39 INFO - 'other-e10s': {'tests': ['a11yr', 12:29:39 INFO - 'ts_paint', 12:29:39 INFO - 'tpaint', 12:29:39 INFO - 'sessionrestore', 12:29:39 INFO - 'sessionrestore_no_auto_restore', 12:29:39 INFO - 'tabpaint']}, 12:29:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:29:39 INFO - 'ts_paint', 12:29:39 INFO - 'tpaint', 12:29:39 INFO - 'sessionrestore', 12:29:39 INFO - 'sessionrestore_no_auto_restore', 12:29:39 INFO - 'tabpaint']}, 12:29:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:29:39 INFO - 'ts_paint', 12:29:39 INFO - 'tpaint', 12:29:39 INFO - 'sessionrestore', 12:29:39 INFO - 'sessionrestore_no_auto_restore', 12:29:39 INFO - 'tabpaint']}, 12:29:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['a11yr', 12:29:39 INFO - 'ts_paint', 12:29:39 INFO - 'tpaint', 12:29:39 INFO - 'sessionrestore', 12:29:39 INFO - 'sessionrestore_no_auto_restore', 12:29:39 INFO - 'tabpaint']}, 12:29:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['a11yr', 12:29:39 INFO - 'ts_paint', 12:29:39 INFO - 'tpaint', 12:29:39 INFO - 'sessionrestore', 12:29:39 INFO - 'sessionrestore_no_auto_restore', 12:29:39 INFO - 'tabpaint']}, 12:29:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['tsvgx', 12:29:39 INFO - 'tsvgr_opacity', 12:29:39 INFO - 'tart', 12:29:39 INFO - 'tscrollx', 12:29:39 INFO - 'cart']}, 12:29:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:29:39 INFO - 'tsvgr_opacity', 12:29:39 INFO - 'tart', 12:29:39 INFO - 'tscrollx', 12:29:39 INFO - 'cart']}, 12:29:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'talos_options': ['--disable-e10s'], 12:29:39 INFO - 'tests': ['tp5o']}, 12:29:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'tests': ['tp5o']}, 12:29:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'talos_options': ['--disable-e10s', 12:29:39 INFO - '--xperf_path', 12:29:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:39 INFO - 'tests': ['tp5n']}, 12:29:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:39 INFO - 'talos_options': ['--xperf_path', 12:29:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:39 INFO - 'tests': ['tp5n']}}, 12:29:39 INFO - 'talos.zip': {'path': '', 12:29:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:29:39 INFO - Running post-action listener: _resource_record_post_action 12:29:39 INFO - ##### 12:29:39 INFO - ##### Finished populate-webroot step (success) 12:29:39 INFO - ##### 12:29:39 INFO - ##### 12:29:39 INFO - ##### Running create-virtualenv step. 12:29:39 INFO - ##### 12:29:39 INFO - Running pre-action listener: _resource_record_pre_action 12:29:39 INFO - Running main action method: create_virtualenv 12:29:39 INFO - Creating virtualenv /builds/slave/test/build/venv 12:29:39 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 12:29:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:29:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:29:39 INFO - Using real prefix '/usr' 12:29:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:29:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:29:42 INFO - Installing pip.................done. 12:29:42 INFO - Return code: 0 12:29:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:29:42 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')]} 12:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:42 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')]} 12:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:42 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:29:42 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 12:29:42 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 12:29:42 INFO - Using env: {'DISPLAY': ':0', 12:29:42 INFO - 'HOME': '/home/cltbld', 12:29:42 INFO - 'LANG': 'en_US.UTF-8', 12:29:42 INFO - 'LANGUAGE': 'en_US:en', 12:29:42 INFO - 'LOGNAME': 'cltbld', 12:29:42 INFO - 'MAIL': '/var/mail/cltbld', 12:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:42 INFO - 'MOZ_NO_REMOTE': '1', 12:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:42 INFO - 'NO_EM_RESTART': '1', 12:29:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:42 INFO - 'PWD': '/builds/slave/test', 12:29:42 INFO - 'SHELL': '/bin/bash', 12:29:42 INFO - 'SHLVL': '1', 12:29:42 INFO - 'TERM': 'linux', 12:29:42 INFO - 'TMOUT': '86400', 12:29:42 INFO - 'USER': 'cltbld', 12:29:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:42 INFO - '_': '/tools/buildbot/bin/python'} 12:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:42 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 12:29:42 INFO - Cleaning up... 12:29:42 INFO - Return code: 0 12:29:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:29:42 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')]} 12:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:42 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')]} 12:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:42 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:29:42 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 12:29:42 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 12:29:42 INFO - Using env: {'DISPLAY': ':0', 12:29:42 INFO - 'HOME': '/home/cltbld', 12:29:42 INFO - 'LANG': 'en_US.UTF-8', 12:29:42 INFO - 'LANGUAGE': 'en_US:en', 12:29:42 INFO - 'LOGNAME': 'cltbld', 12:29:42 INFO - 'MAIL': '/var/mail/cltbld', 12:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:42 INFO - 'MOZ_NO_REMOTE': '1', 12:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:42 INFO - 'NO_EM_RESTART': '1', 12:29:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:42 INFO - 'PWD': '/builds/slave/test', 12:29:42 INFO - 'SHELL': '/bin/bash', 12:29:42 INFO - 'SHLVL': '1', 12:29:42 INFO - 'TERM': 'linux', 12:29:42 INFO - 'TMOUT': '86400', 12:29:42 INFO - 'USER': 'cltbld', 12:29:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:42 INFO - '_': '/tools/buildbot/bin/python'} 12:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:42 INFO - Downloading/unpacking psutil>=3.1.1 12:29:43 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 12:29:43 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 12:29:43 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 12:29:43 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 12:29:43 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 12:29:43 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 12:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:29:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:45 INFO - Installing collected packages: psutil 12:29:45 INFO - Running setup.py install for psutil 12:29:45 INFO - building 'psutil._psutil_linux' extension 12:29:45 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 12:29:47 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 12:29:47 INFO - building 'psutil._psutil_posix' extension 12:29:47 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 12:29:47 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 12:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:47 INFO - Successfully installed psutil 12:29:47 INFO - Cleaning up... 12:29:47 INFO - Return code: 0 12:29:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:29:47 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')]} 12:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:47 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')]} 12:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:47 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:29:47 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 12:29:47 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 12:29:47 INFO - Using env: {'DISPLAY': ':0', 12:29:47 INFO - 'HOME': '/home/cltbld', 12:29:47 INFO - 'LANG': 'en_US.UTF-8', 12:29:47 INFO - 'LANGUAGE': 'en_US:en', 12:29:47 INFO - 'LOGNAME': 'cltbld', 12:29:47 INFO - 'MAIL': '/var/mail/cltbld', 12:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:47 INFO - 'MOZ_NO_REMOTE': '1', 12:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:47 INFO - 'NO_EM_RESTART': '1', 12:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:47 INFO - 'PWD': '/builds/slave/test', 12:29:47 INFO - 'SHELL': '/bin/bash', 12:29:47 INFO - 'SHLVL': '1', 12:29:47 INFO - 'TERM': 'linux', 12:29:47 INFO - 'TMOUT': '86400', 12:29:47 INFO - 'USER': 'cltbld', 12:29:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:47 INFO - '_': '/tools/buildbot/bin/python'} 12:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:29:47 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 12:29:47 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 12:29:47 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 12:29:47 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 12:29:47 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 12:29:47 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 12:29:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:29:49 INFO - Installing collected packages: mozsystemmonitor 12:29:49 INFO - Running setup.py install for mozsystemmonitor 12:29:49 INFO - Successfully installed mozsystemmonitor 12:29:49 INFO - Cleaning up... 12:29:49 INFO - Return code: 0 12:29:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:49 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:29:49 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 12:29:49 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 12:29:49 INFO - Using env: {'DISPLAY': ':0', 12:29:49 INFO - 'HOME': '/home/cltbld', 12:29:49 INFO - 'LANG': 'en_US.UTF-8', 12:29:49 INFO - 'LANGUAGE': 'en_US:en', 12:29:49 INFO - 'LOGNAME': 'cltbld', 12:29:49 INFO - 'MAIL': '/var/mail/cltbld', 12:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:49 INFO - 'MOZ_NO_REMOTE': '1', 12:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:49 INFO - 'NO_EM_RESTART': '1', 12:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:49 INFO - 'PWD': '/builds/slave/test', 12:29:49 INFO - 'SHELL': '/bin/bash', 12:29:49 INFO - 'SHLVL': '1', 12:29:49 INFO - 'TERM': 'linux', 12:29:49 INFO - 'TMOUT': '86400', 12:29:49 INFO - 'USER': 'cltbld', 12:29:49 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:49 INFO - '_': '/tools/buildbot/bin/python'} 12:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:49 INFO - Downloading/unpacking blobuploader==1.2.4 12:29:49 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 12:29:49 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 12:29:49 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 12:29:49 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 12:29:49 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 12:29:49 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 12:29:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:29:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:29:51 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 12:29:51 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 12:29:51 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 12:29:51 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 12:29:51 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 12:29:51 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 12:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:29:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:29:52 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 12:29:52 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 12:29:52 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 12:29:52 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 12:29:52 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 12:29:52 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 12:29:52 INFO - Downloading docopt-0.6.1.tar.gz 12:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:29:52 INFO - Installing collected packages: blobuploader, requests, docopt 12:29:52 INFO - Running setup.py install for blobuploader 12:29:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:29:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:29:52 INFO - Running setup.py install for requests 12:29:52 INFO - Running setup.py install for docopt 12:29:53 INFO - Successfully installed blobuploader requests docopt 12:29:53 INFO - Cleaning up... 12:29:53 INFO - Return code: 0 12:29:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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')]} 12:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:53 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:29:53 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 12:29:53 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 12:29:53 INFO - Using env: {'DISPLAY': ':0', 12:29:53 INFO - 'HOME': '/home/cltbld', 12:29:53 INFO - 'LANG': 'en_US.UTF-8', 12:29:53 INFO - 'LANGUAGE': 'en_US:en', 12:29:53 INFO - 'LOGNAME': 'cltbld', 12:29:53 INFO - 'MAIL': '/var/mail/cltbld', 12:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:53 INFO - 'MOZ_NO_REMOTE': '1', 12:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:53 INFO - 'NO_EM_RESTART': '1', 12:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:53 INFO - 'PWD': '/builds/slave/test', 12:29:53 INFO - 'SHELL': '/bin/bash', 12:29:53 INFO - 'SHLVL': '1', 12:29:53 INFO - 'TERM': 'linux', 12:29:53 INFO - 'TMOUT': '86400', 12:29:53 INFO - 'USER': 'cltbld', 12:29:53 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:53 INFO - '_': '/tools/buildbot/bin/python'} 12:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:53 INFO - Running setup.py (path:/tmp/pip-93flfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:53 INFO - Running setup.py (path:/tmp/pip-65uRH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:53 INFO - Running setup.py (path:/tmp/pip-9QHK7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:53 INFO - Running setup.py (path:/tmp/pip-tsFmPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:53 INFO - Running setup.py (path:/tmp/pip-1nROHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:53 INFO - Running setup.py (path:/tmp/pip-4SIF3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:53 INFO - Running setup.py (path:/tmp/pip-DKzpdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:53 INFO - Running setup.py (path:/tmp/pip-IRqaP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:53 INFO - Running setup.py (path:/tmp/pip-g8EaG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:54 INFO - Running setup.py (path:/tmp/pip-7xdOT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:54 INFO - Running setup.py (path:/tmp/pip-cU2xws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:54 INFO - Running setup.py (path:/tmp/pip-9dMGgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:54 INFO - Running setup.py (path:/tmp/pip-Eme8ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:54 INFO - Running setup.py (path:/tmp/pip-AGTdwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:54 INFO - Running setup.py (path:/tmp/pip-zdmSH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:54 INFO - Running setup.py (path:/tmp/pip-ILYXvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:54 INFO - Running setup.py (path:/tmp/pip-RRN_vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:54 INFO - Running setup.py install for manifestparser 12:29:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:54 INFO - Running setup.py install for mozcrash 12:29:54 INFO - Running setup.py install for mozdebug 12:29:54 INFO - Running setup.py install for mozdevice 12:29:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:29:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for mozfile 12:29:55 INFO - Running setup.py install for mozhttpd 12:29:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for mozinfo 12:29:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for mozInstall 12:29:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for mozleak 12:29:55 INFO - Running setup.py install for mozlog 12:29:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for moznetwork 12:29:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for mozprocess 12:29:55 INFO - Running setup.py install for mozprofile 12:29:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:55 INFO - Running setup.py install for mozrunner 12:29:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:56 INFO - Running setup.py install for mozscreenshot 12:29:56 INFO - Running setup.py install for moztest 12:29:56 INFO - Running setup.py install for mozversion 12:29:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:56 INFO - Cleaning up... 12:29:56 INFO - Return code: 0 12:29:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:56 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')]} 12:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:56 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')]} 12:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:56 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:29:56 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 12:29:56 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 12:29:56 INFO - Using env: {'DISPLAY': ':0', 12:29:56 INFO - 'HOME': '/home/cltbld', 12:29:56 INFO - 'LANG': 'en_US.UTF-8', 12:29:56 INFO - 'LANGUAGE': 'en_US:en', 12:29:56 INFO - 'LOGNAME': 'cltbld', 12:29:56 INFO - 'MAIL': '/var/mail/cltbld', 12:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:56 INFO - 'MOZ_NO_REMOTE': '1', 12:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:56 INFO - 'NO_EM_RESTART': '1', 12:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:56 INFO - 'PWD': '/builds/slave/test', 12:29:56 INFO - 'SHELL': '/bin/bash', 12:29:56 INFO - 'SHLVL': '1', 12:29:56 INFO - 'TERM': 'linux', 12:29:56 INFO - 'TMOUT': '86400', 12:29:56 INFO - 'USER': 'cltbld', 12:29:56 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:56 INFO - '_': '/tools/buildbot/bin/python'} 12:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:56 INFO - Running setup.py (path:/tmp/pip-DlOEbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:56 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)) 12:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:56 INFO - Running setup.py (path:/tmp/pip-Tdg6xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:56 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)) 12:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:56 INFO - Running setup.py (path:/tmp/pip-GvBjaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:56 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)) 12:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:56 INFO - Running setup.py (path:/tmp/pip-dH37h1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:56 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)) 12:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:56 INFO - Running setup.py (path:/tmp/pip-cD6e0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:56 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)) 12:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:56 INFO - Running setup.py (path:/tmp/pip-S7rmoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:57 INFO - Running setup.py (path:/tmp/pip-jAdUOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:57 INFO - Running setup.py (path:/tmp/pip-VzN6bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:57 INFO - Running setup.py (path:/tmp/pip-l0jCJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:57 INFO - Running setup.py (path:/tmp/pip-UPOCLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:57 INFO - Running setup.py (path:/tmp/pip-nRLw_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:57 INFO - Running setup.py (path:/tmp/pip-fjfuUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:57 INFO - Running setup.py (path:/tmp/pip-hupy5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:57 INFO - Running setup.py (path:/tmp/pip-5pWKmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:57 INFO - Running setup.py (path:/tmp/pip-_excCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:57 INFO - Running setup.py (path:/tmp/pip-mqdhNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:57 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)) 12:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:57 INFO - Running setup.py (path:/tmp/pip-CzcsiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:57 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)) 12:29:57 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)) 12:29:57 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)) 12:29:57 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)) 12:29:57 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)) 12:29:57 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)) 12:29:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:57 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 12:29:57 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 12:29:57 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 12:29:57 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 12:29:57 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 12:29:57 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 12:29:59 INFO - Downloading blessings-1.6.tar.gz 12:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:59 INFO - Installing collected packages: blessings 12:29:59 INFO - Running setup.py install for blessings 12:29:59 INFO - Successfully installed blessings 12:29:59 INFO - Cleaning up... 12:29:59 INFO - Return code: 0 12:29:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:00 INFO - Reading from file tmpfile_stdout 12:30:00 INFO - Current package versions: 12:30:00 INFO - argparse == 1.2.1 12:30:00 INFO - blessings == 1.6 12:30:00 INFO - blobuploader == 1.2.4 12:30:00 INFO - docopt == 0.6.1 12:30:00 INFO - manifestparser == 1.1 12:30:00 INFO - mozInstall == 1.12 12:30:00 INFO - mozcrash == 0.17 12:30:00 INFO - mozdebug == 0.1 12:30:00 INFO - mozdevice == 0.48 12:30:00 INFO - mozfile == 1.2 12:30:00 INFO - mozhttpd == 0.7 12:30:00 INFO - mozinfo == 0.9 12:30:00 INFO - mozleak == 0.1 12:30:00 INFO - mozlog == 3.2 12:30:00 INFO - moznetwork == 0.27 12:30:00 INFO - mozprocess == 0.22 12:30:00 INFO - mozprofile == 0.28 12:30:00 INFO - mozrunner == 6.11 12:30:00 INFO - mozscreenshot == 0.1 12:30:00 INFO - mozsystemmonitor == 0.3 12:30:00 INFO - moztest == 0.7 12:30:00 INFO - mozversion == 1.4 12:30:00 INFO - psutil == 3.1.1 12:30:00 INFO - requests == 1.2.3 12:30:00 INFO - wsgiref == 0.1.2 12:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:00 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')]} 12:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:00 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')]} 12:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:00 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:30:00 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 12:30:00 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 12:30:00 INFO - Using env: {'DISPLAY': ':0', 12:30:00 INFO - 'HOME': '/home/cltbld', 12:30:00 INFO - 'LANG': 'en_US.UTF-8', 12:30:00 INFO - 'LANGUAGE': 'en_US:en', 12:30:00 INFO - 'LOGNAME': 'cltbld', 12:30:00 INFO - 'MAIL': '/var/mail/cltbld', 12:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:00 INFO - 'MOZ_NO_REMOTE': '1', 12:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:00 INFO - 'NO_EM_RESTART': '1', 12:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:00 INFO - 'PWD': '/builds/slave/test', 12:30:00 INFO - 'SHELL': '/bin/bash', 12:30:00 INFO - 'SHLVL': '1', 12:30:00 INFO - 'TERM': 'linux', 12:30:00 INFO - 'TMOUT': '86400', 12:30:00 INFO - 'USER': 'cltbld', 12:30:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:00 INFO - '_': '/tools/buildbot/bin/python'} 12:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 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)) 12:30:00 INFO - Cleaning up... 12:30:00 INFO - Return code: 0 12:30:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:30:00 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')]} 12:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:00 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')]} 12:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:00 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 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:30:00 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 12:30:00 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 12:30:00 INFO - Using env: {'DISPLAY': ':0', 12:30:00 INFO - 'HOME': '/home/cltbld', 12:30:00 INFO - 'LANG': 'en_US.UTF-8', 12:30:00 INFO - 'LANGUAGE': 'en_US:en', 12:30:00 INFO - 'LOGNAME': 'cltbld', 12:30:00 INFO - 'MAIL': '/var/mail/cltbld', 12:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:00 INFO - 'MOZ_NO_REMOTE': '1', 12:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:00 INFO - 'NO_EM_RESTART': '1', 12:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:00 INFO - 'PWD': '/builds/slave/test', 12:30:00 INFO - 'SHELL': '/bin/bash', 12:30:00 INFO - 'SHLVL': '1', 12:30:00 INFO - 'TERM': 'linux', 12:30:00 INFO - 'TMOUT': '86400', 12:30:00 INFO - 'USER': 'cltbld', 12:30:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:00 INFO - '_': '/tools/buildbot/bin/python'} 12:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:00 INFO - Downloading/unpacking jsonschema 12:30:00 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 12:30:00 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 12:30:00 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 12:30:00 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 12:30:00 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 12:30:00 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 12:30:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:30:02 INFO - Downloading/unpacking functools32 (from jsonschema) 12:30:02 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 12:30:02 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 12:30:02 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 12:30:02 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 12:30:02 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 12:30:02 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 12:30:02 INFO - Downloading functools32-3.2.3-2.tar.gz 12:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:30:02 INFO - warning: no files found matching '*.txt' 12:30:02 INFO - no previously-included directories found matching 'build' 12:30:02 INFO - no previously-included directories found matching 'dist' 12:30:02 INFO - no previously-included directories found matching '.git*' 12:30:02 INFO - Installing collected packages: jsonschema, functools32 12:30:02 INFO - Running setup.py install for functools32 12:30:02 INFO - warning: no files found matching '*.txt' 12:30:02 INFO - no previously-included directories found matching 'build' 12:30:02 INFO - no previously-included directories found matching 'dist' 12:30:02 INFO - no previously-included directories found matching '.git*' 12:30:02 INFO - Successfully installed jsonschema functools32 12:30:02 INFO - Cleaning up... 12:30:02 INFO - Return code: 0 12:30:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:03 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f26f4bcbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, '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': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', '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 12:30:03 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', 'flake8'] in /builds/slave/test/build 12:30:03 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 flake8 12:30:03 INFO - Using env: {'DISPLAY': ':0', 12:30:03 INFO - 'HOME': '/home/cltbld', 12:30:03 INFO - 'LANG': 'en_US.UTF-8', 12:30:03 INFO - 'LANGUAGE': 'en_US:en', 12:30:03 INFO - 'LOGNAME': 'cltbld', 12:30:03 INFO - 'MAIL': '/var/mail/cltbld', 12:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:03 INFO - 'MOZ_NO_REMOTE': '1', 12:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:03 INFO - 'NO_EM_RESTART': '1', 12:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:03 INFO - 'PWD': '/builds/slave/test', 12:30:03 INFO - 'SHELL': '/bin/bash', 12:30:03 INFO - 'SHLVL': '1', 12:30:03 INFO - 'TERM': 'linux', 12:30:03 INFO - 'TMOUT': '86400', 12:30:03 INFO - 'USER': 'cltbld', 12:30:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:03 INFO - '_': '/tools/buildbot/bin/python'} 12:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:03 INFO - Downloading/unpacking flake8 12:30:03 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 12:30:03 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 12:30:03 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 12:30:03 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 12:30:03 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 12:30:03 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 12:30:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:30:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:30:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 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 12:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:30:05 INFO - no previously-included directories found matching 'docs/_build' 12:30:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:30:05 INFO - Running setup.py install for pep8 12:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:30:05 INFO - no previously-included directories found matching 'docs/_build' 12:30:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:30:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:30:05 INFO - Cleaning up... 12:30:06 INFO - Return code: 0 12:30:06 INFO - Running post-action listener: _resource_record_post_action 12:30:06 INFO - Running post-action listener: _start_resource_monitoring 12:30:06 INFO - Starting resource monitoring. 12:30:06 INFO - ##### 12:30:06 INFO - ##### Finished create-virtualenv step (success) 12:30:06 INFO - ##### 12:30:06 INFO - ##### 12:30:06 INFO - ##### Running install step. 12:30:06 INFO - ##### 12:30:06 INFO - Running pre-action listener: _resource_record_pre_action 12:30:06 INFO - Running main action method: install 12:30:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:06 INFO - Reading from file tmpfile_stdout 12:30:06 INFO - Detecting whether we're running mozinstall >=1.0... 12:30:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:30:06 INFO - Reading from file tmpfile_stdout 12:30:06 INFO - Output received: 12:30:06 INFO - Usage: mozinstall [options] installer 12:30:06 INFO - Options: 12:30:06 INFO - -h, --help show this help message and exit 12:30:06 INFO - -d DEST, --destination=DEST 12:30:06 INFO - Directory to install application into. [default: 12:30:06 INFO - "/builds/slave/test"] 12:30:06 INFO - --app=APP Application being installed. [default: firefox] 12:30:06 INFO - mkdir: /builds/slave/test/build/application 12:30:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:30:22 INFO - Reading from file tmpfile_stdout 12:30:22 INFO - Output received: 12:30:22 INFO - /builds/slave/test/build/application/firefox/firefox 12:30:22 INFO - Running post-action listener: _resource_record_post_action 12:30:22 INFO - ##### 12:30:22 INFO - ##### Finished install step (success) 12:30:22 INFO - ##### 12:30:22 INFO - ##### 12:30:22 INFO - ##### Running run-tests step. 12:30:22 INFO - ##### 12:30:22 INFO - Running pre-action listener: _resource_record_pre_action 12:30:22 INFO - Running main action method: run_tests 12:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:22 INFO - Python 2.7.3 12:30:22 INFO - Return code: 0 12:30:22 INFO - grabbing minidump binary from tooltool 12:30: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')]} 12:30:22 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 0x1a759c0>, '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 0x1a768c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a76d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:22 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 12:30:22 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 12:30:22 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 12:30:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:22 INFO - Return code: 0 12:30:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:30:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:30:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:30:23 INFO - Return code: 0 12:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:30:23 INFO - Using env: {'DISPLAY': ':0', 12:30:23 INFO - 'HOME': '/home/cltbld', 12:30:23 INFO - 'LANG': 'en_US.UTF-8', 12:30:23 INFO - 'LANGUAGE': 'en_US:en', 12:30:23 INFO - 'LOGNAME': 'cltbld', 12:30:23 INFO - 'MAIL': '/var/mail/cltbld', 12:30:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:23 INFO - 'MOZ_NO_REMOTE': '1', 12:30:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:23 INFO - 'NO_EM_RESTART': '1', 12:30:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:23 INFO - 'PWD': '/builds/slave/test', 12:30:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:23 INFO - 'SHELL': '/bin/bash', 12:30:23 INFO - 'SHLVL': '1', 12:30:23 INFO - 'TERM': 'linux', 12:30:23 INFO - 'TMOUT': '86400', 12:30:23 INFO - 'USER': 'cltbld', 12:30:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687', 12:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:23 INFO - '_': '/tools/buildbot/bin/python'} 12:30:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:30:23 INFO - mozversion application_buildid: 20160531111747 12:30:23 INFO - mozversion application_changeset: ea5c88b997faa8be5ef6a936304a908fd0292894 12:30:23 INFO - mozversion application_display_name: Nightly 12:30:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:23 INFO - mozversion application_name: Firefox 12:30:23 INFO - mozversion application_remotingname: firefox 12:30:23 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 12:30:23 INFO - mozversion application_vendor: Mozilla 12:30:23 INFO - mozversion application_version: 49.0a1 12:30:23 INFO - mozversion platform_buildid: 20160531111747 12:30:23 INFO - mozversion platform_changeset: ea5c88b997faa8be5ef6a936304a908fd0292894 12:30:23 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 12:30:23 INFO - mozversion platform_version: 49.0a1 12:30:23 INFO - using testdate: 1464723023 12:30:23 INFO - actual date: 1464723023 12:30:23 INFO - starting webserver on 'localhost:52170' 12:30:23 INFO - SUITE-START | Running 2 tests 12:30:23 INFO - TEST-START | dromaeo_css 12:30:23 INFO - operating with platform_type : linux_ 12:30:23 INFO - Initialising browser for dromaeo_css test... 12:30:23 INFO - TEST-INFO | started process 3304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnaBhGQ/profile http://localhost:52170/getInfo.html) 12:30:30 INFO - PROCESS | 3304 | __metrics Screen width/height:1600/1200 12:30:30 INFO - PROCESS | 3304 | colorDepth:24 12:30:30 INFO - PROCESS | 3304 | Browser inner width/height: 1024/697 12:30:30 INFO - PROCESS | 3304 | __metrics 12:30:30 ERROR - PROCESS | 3304 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 12:30:31 INFO - TEST-INFO | 3304: exit 0 12:30:31 INFO - Browser initialized. 12:30:31 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:31 INFO - TEST-INFO | started process 3422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnaBhGQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:43 INFO - PROCESS | 3422 | RSS: Main: 167931904 12:30:43 INFO - PROCESS | 3422 | 12:31:33 INFO - PROCESS | 3422 | 1464723093408 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] 12:33:45 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:52170/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52170/tests/dromaeo/cssquery-ext.html) 12:33:46 INFO - PROCESS | 3422 | RSS: Main: 170704896 12:33:46 INFO - PROCESS | 3422 | 12:36:48 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:52170/tests/dromaeo/cssquery-ext.html (next: http://localhost:52170/tests/dromaeo/cssquery-jquery.html) 12:36:48 INFO - PROCESS | 3422 | RSS: Main: 169824256 12:36:48 INFO - PROCESS | 3422 | 12:39:50 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:52170/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52170/tests/dromaeo/cssquery-mootools.html) 12:39:51 INFO - PROCESS | 3422 | RSS: Main: 171057152 12:39:51 INFO - PROCESS | 3422 | 12:42:53 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:52170/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52170/tests/dromaeo/cssquery-prototype.html) 12:42:53 INFO - PROCESS | 3422 | RSS: Main: 168968192 12:42:53 INFO - PROCESS | 3422 | 12:45:56 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:52170/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52170/tests/dromaeo/cssquery-yui.html) 12:45:57 INFO - PROCESS | 3422 | RSS: Main: 170684416 12:45:57 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:52170/tests/dromaeo/cssquery-yui.html (next: http://localhost:52170/tests/dromaeo/cssquery-dojo.html) 12:48:59 INFO - PROCESS | 3422 | RSS: Main: 172236800 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | __start_tp_report 12:48:59 INFO - PROCESS | 3422 | _x_x_mozilla_page_load 12:48:59 INFO - PROCESS | 3422 | _x_x_mozilla_page_load_details 12:48:59 INFO - PROCESS | 3422 | |i|pagename|runs| 12:48:59 INFO - PROCESS | 3422 | |0;dojo.html;2674.050711997241;2696.9212315073974;2707.9729202707967;2711.7288271172883;2717.225590706648;4657.2082745933185;4690.1792186367375;4694.554017368353;4752.928706069411;4810.4708482067035;4686.55477729616;4729.763511824403;4765.571098601125;4770.4513980892125;4775.403074615673;4276.294411422112;4303.591158839921;4350.78246087696;4398.164348773738;4400.185965596359;4092.8152619305815;4444.133393988173;4456.041950980539;4471.374007638933;4478.305862591304;4291.399204111427;4343.782810859444;4346.521882592944;4360.08438227971;4441;4309.461317335333;4323.567643235683;4324.027093903878;4324.200022995725;4359.716618419792;4353.216421044211;4356.237658409766;4366.934495982563;4372.628326592244;4402.8679139625865;4648.837290694843;4668.696534725266;4688.624910007191;4728.338032675429;4734.289856521515;5118.155504341779;5129.230615407681;5142.871428214287;5151.201563757586;5182.32629758129;4991.376077990251;5147.202183661539;5174.637775355726;5174.793008279665;5178.741062946838;4739.336492890998;4761.571458568711;4780.1873681474235;4804.8318308859025;4804.99095190014;5230.843074707765;5231.29377534028;5234.057869583511;5261.421243663194;5286.947130528645;4937.950620493821;4976;5030;5046.343975283263;5068.391792984866;5065.821696670393;5071.746412679351;5081.974590127025;5090;5148.485151484896;4984.077945580079;5015.924761128586;5017.347744793226;5020.422651395136;5078.7206703631655;4900.730459824744;5030.547250747377;5033.19468884984;5059.544640982255;5070.797168113234;4382.05785756064;4398.252297109499;4411.360352748898;4442.88927768058;4456.866294011184;3807.2575847709986;3829.272438236726;3846.0384903774057;3871.4192871069004;3876.612338766101;4345.044090300129;4388.122375524844;4407.140607581555;4453.1316393303;4470.307102399133;5081.339425874613;5097.235414687826;5104.05574968632;5121.795128194905;5155.845324640267;4400.867973960786;4404.031113155101;4407.140607581491;4440.822367105344;4447.777611119431;4523.140603285366;4524.343970124313;4528.6150677192145;4560.521145279764;4563.155816174019;4751.83368582098;4759.214729569582;4761.2382018876815;4761.952380476151;4804.615630749601;5112.79548818058;5115.948840511547;5140.46025167352;5140.537351638221;5189.532942035235;4734.479207287127;4799.616030717604;4803.78382972774;4845.5881250094135;4847.297141914332;5092.643514954066;5099.592032637306;5106.897862042812;5110.66780659256;5151.5363617274625;4924.679895806761;5178.223266510054;5181.145111056633;5190.377154741455;5191.402988656202;4851.830185943475;4889.242167464049;4982.227754698028;5014.6991180529285;5018.4730603286125;4914.631402644744;4978.925316120189;4982.626303027215;4990.5508504234795;5019.573336266459;4743.881402964954;4869.610431165569;4928.236123400879;4928.654994150375;4932.358793356841;4957.4794646561595;4991.750412479434;5012.799488020439;5016.1221786188;5029.346184996073;4873.561379475864;4902.754862256944;4917.311576379238;4922.212446008622;4932.950670493393 12:48:59 INFO - PROCESS | 3422 | |1;ext.html;545.4149509027559;587.9882402351952;594.622414766623;604.1330690459189;605.2313561776543;28158.155255342346;40544.189116217654;41109.38335924956;41617.375739363895;45236.547634523646;42199;42688.78655606725;42753.14493710124;42845.57154428455;42959;7620.96189519053;7748.108967468757;7769.145394006655;7828.765137045898;7833.412494062968;7537.585432801194;7721.845563088735;7739.342155916754;7741.419393545479;7754.06951165858;7701.576413297266;7704.730334438295;7715.035620547432;7717.228277172294;7765.7670269892;7694.999999999972;7695.268949449794;7735.110462296851;7740.496867703581;7754.495957762728;7589.392848572101;7613.733519326797;7684.3084122429245;7686.385089192851;7696.230376962259;38979.41530877039;39421.80289098565;39431;39469.210615787524;39486.21027579461;3040.443257305691;3173.397054559626;3349.581302337208;3604.279144171176;3688.483612294281;28128;29644.407111857858;29991;30211.244718881982;30795.846020769754;3568.660977207161;3622.5652921649307;3648.361536731088;3650.817459127033;3674.706023518112;266831;267583;268582;268953;269916;32485;50312.74218144503;50435.24347134796;50650.746746266035;50699.23951140736;33146.17134571655;34928.65071349254;34935;34938.82530587331;34977.47533786995;12172.330521821385;12193.39033048344;12221.511139554495;12284.201526900722;12306.876931230752;11709.765804683859;11804;11833.171677982458;11836.763264734656;11873.109516786275;8000.279974802295;8034.35725141999;8035.959820200862;8056.959715201387;8092.473989190801;3101.395227930577;3108.9067327980197;3128.765342599314;3148.937021259562;3178.8410579470933;875.798566329735;942.3497786527274;976.3946353261024;996.9252306076928;1066.3974854207347;28888.277793055342;29005;29142.854285728437;29211.415771684446;29470.263243418656;921.8663293822358;976.2677991506371;977.9315447918719;1032.808930347873;1059.89930956559;14977.925110374379;15219.467318643794;15222.771658425132;15389.153596552296;15482.22588870552;391382;391840;393113;393624;394891;51441;51785.48214517882;51799;51946;52083.57289630348;1211.4669545400172;1220.4873952939786;1253.5424570032055;1258.1381753498883;1272.8663490333568;26861.731382686703;28425.715742843135;28476;28565.714342857136;28581.42837143287;41820.79089604533;42067.57932420637;42330.1533969325;42373.36439953341;42458.240214385965;330112;334575;334587;334746;335247;25747.742522575285;25778.226653200432;26191.607125893508;26609.201723948314;26666.06668766584;43577;44053.55946440495;44402.11195776002;44429.555704442544;44904.77547612241;36875.63124368722;36974.6302536982;37016;37437;37457.81271093627;34537.827310863286;36165.819170903975;36337.454938176474;36391.818040910686;36499;33284.1678958028;35838.13294936451;37165;37475.625243747214;37966.62033379631;33246;34183.145421364665;34762.478562822085;34855.30289394147;34991.475127873615;26315;26390;26532.97878721244;26762.92948282047;26933.192004239903 12:48:59 INFO - PROCESS | 3422 | |2;jquery.html;379.51043154330915;379.7303914220903;380.78485655604584;387.0748910104848;393.79719444486096;15188.696226075472;16114.838851611481;16892.577685557866;16920.661586768256;16950.576235594115;12343.629691109281;14636.560903172895;16847;16955.067471289076;17048.147592620382;4703.129920964615;4829.806807727704;4858.878528036809;4860.684055536397;4883.853484395474;3440.7591468597207;5323.494268044529;5618.241537392461;5618.4662457066515;5666.858328541778;5415.918761218585;5634.661920284776;5641.294838145232;5642.74607642655;5664.150377443396;5539.889202215933;5556.16657501378;5582.27430434043;5583.246261754672;5596.944030559704;5241.9737901310245;5390.407055223922;5396.298481197458;5405.162199859028;5506.972465137689;12350.382480876011;12401;12403.441845116991;12427.75144497114;12531.874681253163;1740.7562941188244;1803.7204233343853;1809.7749700771337;1835.3392778599828;1935.1968932892833;8751.693690720795;9134.543272836398;9179.26565874735;9251.444913305224;9305.39514931534;1660.286076986895;1711.7945846498376;1729.619483713581;1738.452387497934;1752.3253547384222;65302.67348663274;66340.6682966587;66698.3330166697;67524.32475675229;68139.65930170366;9781.510924453749;9793.265505087147;10124;10381.63664271762;10424.739381515523;14248.073875198079;14282.21447820359;14356.784648230285;14401.855981440052;14464.783028254584;7706.152323244439;7761.8447631047065;7788.84422311562;7810.570418626917;7971.880421793678;7402.185759566443;7493.85012299751;7614.505057171266;7653.272939070779;7664.846703065908;4290.914181716348;4398.670099742532;4406.074724307931;4508.706934049276;4544.909101818012;1688.3584237989485;1731.0998280893864;1872.850171986238;1887.5564242403025;1907.3419670213755;410.51969196040704;413.86342506972625;419.15958503200886;830.3481766813023;895.4045559702855;10450.16398688103;10474.895251047545;10690.358578485315;10695.197860160519;10785.946070269598;387.5078650114339;390.6874500399669;417.8934371735188;422.2800125785528;425.60418810505905;5580.414056524007;5696.3733989261145;5706.600537962386;5805.941940580625;5821.767129314865;381828;381917;382432;384549;391478;79854.20145798584;80252;80715.59642201751;80791;81109;401.684677528139;405.5376870367724;406.96744260459093;408.61998341542653;409.8483561082419;9567.330286879851;9605.663801766905;9758.609655613696;9762.730844990107;9899.109080182818;34454.48318275178;34479.4828077584;34647.82676086603;34750.47874281938;35814.64185358113;49254.75372623257;49453.25820112629;50207.24689129593;50456;50574.24138637997;7420;7600.923990760021;7768.61156942162;7807.843843123219;7913.643886025257;8349.03986041613;9061.68284110053;10270.794584108127;10644.254902157085;11352.545898164311;9021.413608115452;9415.811683766424;9445.811083778424;9474.763130921781;9993.054826895239;10322.277440579388;10351.844722329322;10426.478676066317;10502.579896804042;10514.947425262824;9677.080677335642;9784.168345690412;9841.557129929028;9873.210143188384;9891.060349266809;7657.808554786175;7679.769606911802;7834.334081603128;8054.5569993650915;8089.191080891864;7547.245275472409;8797.780055498664;8969.7757556059;9048.728538143867;9084.727458176265 12:48:59 INFO - PROCESS | 3422 | |3;mootools.html;1398.3777219137482;1444.4438891026955;1535.2860919672357;1723.5174151237659;1740.7475915992175;6910.654467276641;7519.774406767792;7554.660040298186;7983.241592048754;7983.640736166872;7563.621818909046;7749.573773442458;7781.144074151839;7815.882761758564;7867.960660196705;2214.7231596050533;2257.7742225777374;2259.243153543565;2260.513989492259;2262.8189744820456;2202.7136472258585;2228.565429741201;2265.478939843837;2267.8979445924974;2271.068861766676;2210.9336719898347;2220.855644383118;2224.443889027743;2234.5419189066283;2267.036509483471;2135.9145634174693;2146.6994620753107;2151.268568686654;2157.2665293800183;2163.3942496101117;2106.820920221783;2112.7359080114984;2114.016982103329;2114.376259003582;2114.503091773432;7825.7260995865445;7845.607719614053;7883.132855385903;7941.086775020831;7944.0467143942515;4514.277715565494;4622.098690755304;4636.536346365337;4641.907161856778;4645.907081858344;2909.636295463067;2997.565353023821;3081.4915538936048;3087.120170751325;3099.9535006974916;1003.4932359158652;1040.1834559870465;1046.5918291866178;1047.3140093238865;1056.6037735849056;10683;10686.038256556869;10692.62575809843;10698.51856666452;10745.892541074567;4083.448734420815;4140.192662430857;4212.789360531961;4231.830726770895;4283.571642835692;8948.642054317885;8975.775605609912;8992.415492992934;8994.50530220831;9002;4619.653525985565;4627.051454451829;4681.040386720714;4684.391029166255;4690.249560070372;3811.447340135665;4175.603317684815;4265.445492086071;4278.229918614679;4305.07440900208;3639.8180090995875;3651.685393258425;3693.076730817296;3722.6091260417807;3757.0795155186606;1125.0605744203517;1127.6504283672089;1131.3833960491552;1142.457332766929;1187.8930896219208;753.1978442958232;796.5539297993142;809.340387584117;821.7452589697212;822.4653974916353;4894.828680996148;4979.153543897546;5100.0819852426175;5147.7683504242395;5467.289252397242;867.5965675960582;900.0729248873671;910.0808183734476;928.535732133933;937.5359197197295;3710.4990826238654;3794.829232684536;3795.4306853972125;3828.2917660232947;3878.8254528545717;9330.486823224652;9521.857172142423;9554.904450955402;9645.131938125602;9648.807023859483;9761.072698093813;9807.215422766161;9854.753631159278;9862;9866.358686685342;6916.6541672916155;7361.48469607133;7447.441441891771;7528.661210245552;7529;5381.246625472515;5417.945820541901;5487.368952570506;5557.805476808292;5575.386707462257;8841.911580884365;9026.232770214605;9057.68298109563;9074.500902450429;9121.133492318218;8567.314614830673;9030.232430243503;9115.316351273548;9135.995040545402;9181.127792859668;7331.156916954618;7369;7503.512271702322;7529.924700752922;7539.0576177977755;7431.925680743339;7470.8505829882015;7485.925140748523;7492.512986656068;7543.7736867894055;6613.27254002069;6785.321467853175;6836.282190370139;6860.073890024983;6870.141232345957;6738.663066846736;6772.695228714621;6813.0461735358085;6866.553674011173;6882.724691012303;6139.84650383744;6273.027680709315;6277.905831412441;6327.841803954754;6348.682565871781;4589.954100458953;4628.652851036119;4647.744374059459;4704.411948506437;4736.2185239436485;4160.521540022936;4210.221109094828;4244.511881133709;4254.298040823353;4293.95706042948 12:48:59 INFO - PROCESS | 3422 | |4;prototype.html;291.7665867306153;299.0922550060566;305.9066984569707;306.19470791817514;312.2552711782398;4743;4833.903321933559;4889.168841296979;4987.154752741486;5002.5247601477895;4885.462599114095;4894.265860120975;4913.852584422464;4915.729634870081;4957.281194226835;3915.5105611798526;3924.3917192835083;3924.450576919219;3929.9017524561973;3948.644621984021;3617.385044542421;3914.178022615254;3927.2145570885796;3942.8619998300064;3963.9405408918888;3748.11919198988;3770.5852356240794;3771.2646034023237;3785.5835858055593;3789.450529673196;3796.52543432071;3797.6771974382073;3805.2009078093356;3807.7905715185793;3829.636184562462;3368.073779710575;3370.2416956184907;3374.2913988062537;3375.8649654014057;3385.9492107618407;4697.694649847783;4732.503087175832;4738.502457241974;4785.832495862628;4806.870385459414;4339.197248509005;4353.738775673438;4365.105153443536;4374.168907907488;4377.671674624416;5114.820981265675;5129.230615407681;5150.587952963754;5152.252923326097;5162.741862906877;3611.9097022574383;3644.7084233261276;3700.3154416433044;3708.9586539038;3710.666040056381;5336.119540275849;5367.275417818622;5377.865553361197;5388.73056347185;5390.649607775482;3762.793046382475;3767.3972164453558;3781.489498917611;3789.6399842015517;3795.6394142556414;3802.961970380316;3819.579846216914;3824.4454554089502;3829.5978922213326;3849.1147036181274;4078.4494093297617;4268.786560671954;4339.652827773771;4357.8256869724855;4406.228909940747;5098.46466121052;5124.15451450514;5151.072806894795;5152.433232344439;5180.507851754077;1241.490988694631;1276.304414094321;1293.683047653331;1300.6098170548798;1300.752856957175;831.1895901495993;832.6003518311245;851.421033697092;852.953087580189;857.0786404615063;4080.163566468867;4100.753954762724;4107.589241075928;4120.402541631447;4134.64855487281;4757.286407038902;4759.9052217989365;4761.880952976203;4763.54746299101;4765.713257420477;4090.9181816363507;4160.2303573838935;4176.4570605821045;4186.5604111568455;4197.097624010852;2609.8956041758115;2648.55653668751;2831.603575499432;2841.8152820067044;2865.369618683886;4981.153203955264;4983.277424773388;5011.1481048221885;5020.648554601143;5028.723420211851;5326.893462130735;5426.988021647944;5472.589555783332;5481.232627432163;5505.476979686923;4946.975265123651;4950.455449900508;4958.206686930074;4993.675411098267;5012.523810238022;5338;5345.102488616641;5353.78584856617;5363.892722145457;5371.919421208763;5036.420811606712;5207.635465517377;5221.321228240305;5230.320058392384;5237.607179461479;4995.900081998412;5004.599632029502;5010.348654674869;5046.59627229828;5049.671771334852;3731.6641502264924;3748.744170702845;3760.247950409874;3768.736188466781;3769.4534292526882;5199.792008319776;5323.8287576733055;5384.730763461889;5419.891602167856;5438.830651409808;5164.7934082636275;5171.689698618095;5178.533931946143;5183.222516622386;5194.662346947436;5156.246876062157;5157.819476318311;5172.482751724798;5196.907839835292;5209.583233341248;4914.926276105933;4933.358663373739;5051.87370315745;5095.312132862165;5099.22092459603;5146.382434107931;5190.948090519047;5214.739263036757;5217.634765566374;5238.292830467991;5043.067032598981;5073.492650734897;5074.122574647404;5084.923726144185;5098.541131298052 12:48:59 INFO - PROCESS | 3422 | |5;yui.html;699.870523953069;715.2239819795525;721.8989341492193;730.9122905251369;733.5305404541093;11843;12153.392330383489;12210.816837747441;12267.018638508931;12936.353182340892;14267.857321426782;14339.354729037192;14339.498117565887;14356.425742970294;14364.066335688172;794.7814351053448;801.683335082642;806.399232571732;806.4556424413527;807.4630370803438;635.3043417457876;772.8377040821434;799.2487062161549;799.2806474173233;801.0627565748059;822.6873787960567;824.5052968219051;825.8265764189528;826.1201820061615;826.9545175015372;815.7675062607187;820.4134044158455;821.3511326052411;822.0998007182126;825.3273582030656;596.4006173795351;597.2385208858697;597.2653636027667;600.3756093662586;604.6795198544778;12057.517699292017;12187.39063046853;12220.266783992989;12221.572244971472;12228.449719762542;12105.87894121065;12401.441935112942;12452.128351015432;12631.178973366701;12651.620451386472;9741.464219467927;9783.706488805346;9884.604615815359;9939.55272012761;10048.698539043768;3265.118418027143;3266.2324353776844;3295.86445345826;3296.620888597794;3307.58655168104;47863;48095;48156.27765583519;48345.516544834805;48408;9402.95298523503;9574.712758617252;9671.516424178762;9694.22446204302;9754.414735115915;16950.745738813926;17146.828531714524;17155.228014739365;17177.65644687099;17201.7419738704;6330;6334.556581039283;6516.2506311773805;6536.182977127859;6550.377714117151;3508.9649103508636;3624.655657712513;3705.8517659293866;3757.56787969387;3758.5113935188797;2917.795754297179;3003.4743919813945;3010.503266960971;3026.5914101596445;3027.470192716266;496.4786973677624;511.8976204759063;519.4908989190615;522.8274669359103;527.1486549223054;608.349066498842;627.0719335383658;635.6694518850172;664.5082638847315;665.1021121485956;8426.4944103354;8509.702160424355;8553.786155346166;8613.26787223093;9023.864642030374;505.15638883065367;528.2419727690776;563.3211979564614;641.9518536109811;643.2795269298416;4005.2790497710107;4037.9394309085387;4092.856750013735;4162.542120366757;4185.916281674349;54077;56535;56955.43044569584;57171;57334;48915.51084489181;50455.74772126189;50829.42535171852;50859.49140508621;51262.231066534034;46227;46428.5357146431;46534.30198547024;46784.76607617008;47103.52896470991;7805.102413222552;7891.250331218755;8009.439339246197;8055.113937466756;8142.4300298978505;31571.21071973219;31983.200419989687;31991;32146.51780223345;32308.35383292368;36240.27519449649;38258.23483530258;41135.1772964533;41193.794031029654;41866.79066604647;10796.244262901522;10966.342019478856;10995.945020275167;11209.663710088711;11432.256903301259;12059.638210853689;12380.195287306296;12392.194507356993;12435.129540932048;12537;11363.829542557034;11812.350320732443;12063.517459301529;12102.697432564257;12423.44094515767;11750.88249117532;11784.410779461165;11934.462949167135;11941;12133;11441.885581144083;11689.88310116922;11773.587924422603;11794.76410471803;11827.231229970024;9524.761880953032;9608.13526782593;9626.759331016781;9660.3237773358;9678.322517423712;9823.165030972448;10092.747681308027;10210.642627508001;10268.743281418025;10281.84577231357 12:48:59 INFO - PROCESS | 3422 | __end_tp_report 12:48:59 INFO - PROCESS | 3422 | __start_cc_report 12:48:59 INFO - PROCESS | 3422 | _x_x_mozilla_cycle_collect,402 12:48:59 INFO - PROCESS | 3422 | __end_cc_report 12:48:59 INFO - PROCESS | 3422 | __startTimestamp1464724139863__endTimestamp 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | ------- Summary: start ------- 12:48:59 INFO - PROCESS | 3422 | Number of tests: 6 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | [#0] dojo.html Cycles:165 Average:4713.06 Median:4804.99 stddev:489.07 (10.2%) stddev-sans-first:463.67 12:48:59 INFO - PROCESS | 3422 | Values: 2674.1 2696.9 2708.0 2711.7 2717.2 4657.2 4690.2 4694.6 4752.9 4810.5 4686.6 4729.8 4765.6 4770.5 4775.4 4276.3 4303.6 4350.8 4398.2 4400.2 4092.8 4444.1 4456.0 4471.4 4478.3 4291.4 4343.8 4346.5 4360.1 4441.0 4309.5 4323.6 4324.0 4324.2 4359.7 4353.2 4356.2 4366.9 4372.6 4402.9 4648.8 4668.7 4688.6 4728.3 4734.3 5118.2 5129.2 5142.9 5151.2 5182.3 4991.4 5147.2 5174.6 5174.8 5178.7 4739.3 4761.6 4780.2 4804.8 4805.0 5230.8 5231.3 5234.1 5261.4 5286.9 4938.0 4976.0 5030.0 5046.3 5068.4 5065.8 5071.7 5082.0 5090.0 5148.5 4984.1 5015.9 5017.3 5020.4 5078.7 4900.7 5030.5 5033.2 5059.5 5070.8 4382.1 4398.3 4411.4 4442.9 4456.9 3807.3 3829.3 3846.0 3871.4 3876.6 4345.0 4388.1 4407.1 4453.1 4470.3 5081.3 5097.2 5104.1 5121.8 5155.8 4400.9 4404.0 4407.1 4440.8 4447.8 4523.1 4524.3 4528.6 4560.5 4563.2 4751.8 4759.2 4761.2 4762.0 4804.6 5112.8 5115.9 5140.5 5140.5 5189.5 4734.5 4799.6 4803.8 4845.6 4847.3 5092.6 5099.6 5106.9 5110.7 5151.5 4924.7 5178.2 5181.1 5190.4 5191.4 4851.8 4889.2 4982.2 5014.7 5018.5 4914.6 4978.9 4982.6 4990.6 5019.6 4743.9 4869.6 4928.2 4928.7 4932.4 4957.5 4991.8 5012.8 5016.1 5029.3 4873.6 4902.8 4917.3 4922.2 4933.0 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | [#1] ext.html Cycles:180 Average:47776.96 Median:20275.17 stddev:88532.08 (436.7%) stddev-sans-first:88709.41 12:48:59 INFO - PROCESS | 3422 | Values: 545.4 588.0 594.6 604.1 605.2 28158.2 40544.2 41109.4 41617.4 45236.5 42199.0 42688.8 42753.1 42845.6 42959.0 7621.0 7748.1 7769.1 7828.8 7833.4 7537.6 7721.8 7739.3 7741.4 7754.1 7701.6 7704.7 7715.0 7717.2 7765.8 7695.0 7695.3 7735.1 7740.5 7754.5 7589.4 7613.7 7684.3 7686.4 7696.2 38979.4 39421.8 39431.0 39469.2 39486.2 3040.4 3173.4 3349.6 3604.3 3688.5 28128.0 29644.4 29991.0 30211.2 30795.8 3568.7 3622.6 3648.4 3650.8 3674.7 266831.0 267583.0 268582.0 268953.0 269916.0 32485.0 50312.7 50435.2 50650.7 50699.2 33146.2 34928.7 34935.0 34938.8 34977.5 12172.3 12193.4 12221.5 12284.2 12306.9 11709.8 11804.0 11833.2 11836.8 11873.1 8000.3 8034.4 8036.0 8057.0 8092.5 3101.4 3108.9 3128.8 3148.9 3178.8 875.8 942.3 976.4 996.9 1066.4 28888.3 29005.0 29142.9 29211.4 29470.3 921.9 976.3 977.9 1032.8 1059.9 14977.9 15219.5 15222.8 15389.2 15482.2 391382.0 391840.0 393113.0 393624.0 394891.0 51441.0 51785.5 51799.0 51946.0 52083.6 1211.5 1220.5 1253.5 1258.1 1272.9 26861.7 28425.7 28476.0 28565.7 28581.4 41820.8 42067.6 42330.2 42373.4 42458.2 330112.0 334575.0 334587.0 334746.0 335247.0 25747.7 25778.2 26191.6 26609.2 26666.1 43577.0 44053.6 44402.1 44429.6 44904.8 36875.6 36974.6 37016.0 37437.0 37457.8 34537.8 36165.8 36337.5 36391.8 36499.0 33284.2 35838.1 37165.0 37475.6 37966.6 33246.0 34183.1 34762.5 34855.3 34991.5 26315.0 26390.0 26533.0 26762.9 26933.2 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | [#2] jquery.html Cycles:180 Average:23278.33 Median:49354.01 stddev:63674.06 (129.0%) stddev-sans-first:63829.47 12:48:59 INFO - PROCESS | 3422 | Values: 379.5 379.7 380.8 387.1 393.8 15188.7 16114.8 16892.6 16920.7 16950.6 12343.6 14636.6 16847.0 16955.1 17048.1 4703.1 4829.8 4858.9 4860.7 4883.9 3440.8 5323.5 5618.2 5618.5 5666.9 5415.9 5634.7 5641.3 5642.7 5664.2 5539.9 5556.2 5582.3 5583.2 5596.9 5242.0 5390.4 5396.3 5405.2 5507.0 12350.4 12401.0 12403.4 12427.8 12531.9 1740.8 1803.7 1809.8 1835.3 1935.2 8751.7 9134.5 9179.3 9251.4 9305.4 1660.3 1711.8 1729.6 1738.5 1752.3 65302.7 66340.7 66698.3 67524.3 68139.7 9781.5 9793.3 10124.0 10381.6 10424.7 14248.1 14282.2 14356.8 14401.9 14464.8 7706.2 7761.8 7788.8 7810.6 7971.9 7402.2 7493.9 7614.5 7653.3 7664.8 4290.9 4398.7 4406.1 4508.7 4544.9 1688.4 1731.1 1872.9 1887.6 1907.3 410.5 413.9 419.2 830.3 895.4 10450.2 10474.9 10690.4 10695.2 10785.9 387.5 390.7 417.9 422.3 425.6 5580.4 5696.4 5706.6 5805.9 5821.8 381828.0 381917.0 382432.0 384549.0 391478.0 79854.2 80252.0 80715.6 80791.0 81109.0 401.7 405.5 407.0 408.6 409.8 9567.3 9605.7 9758.6 9762.7 9899.1 34454.5 34479.5 34647.8 34750.5 35814.6 49254.8 49453.3 50207.2 50456.0 50574.2 7420.0 7600.9 7768.6 7807.8 7913.6 8349.0 9061.7 10270.8 10644.3 11352.5 9021.4 9415.8 9445.8 9474.8 9993.1 10322.3 10351.8 10426.5 10502.6 10514.9 9677.1 9784.2 9841.6 9873.2 9891.1 7657.8 7679.8 7834.3 8054.6 8089.2 7547.2 8797.8 8969.8 9048.7 9084.7 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | [#3] mootools.html Cycles:180 Average:5183.46 Median:4936.99 stddev:2884.46 (58.4%) stddev-sans-first:2878.52 12:48:59 INFO - PROCESS | 3422 | Values: 1398.4 1444.4 1535.3 1723.5 1740.7 6910.7 7519.8 7554.7 7983.2 7983.6 7563.6 7749.6 7781.1 7815.9 7868.0 2214.7 2257.8 2259.2 2260.5 2262.8 2202.7 2228.6 2265.5 2267.9 2271.1 2210.9 2220.9 2224.4 2234.5 2267.0 2135.9 2146.7 2151.3 2157.3 2163.4 2106.8 2112.7 2114.0 2114.4 2114.5 7825.7 7845.6 7883.1 7941.1 7944.0 4514.3 4622.1 4636.5 4641.9 4645.9 2909.6 2997.6 3081.5 3087.1 3100.0 1003.5 1040.2 1046.6 1047.3 1056.6 10683.0 10686.0 10692.6 10698.5 10745.9 4083.4 4140.2 4212.8 4231.8 4283.6 8948.6 8975.8 8992.4 8994.5 9002.0 4619.7 4627.1 4681.0 4684.4 4690.2 3811.4 4175.6 4265.4 4278.2 4305.1 3639.8 3651.7 3693.1 3722.6 3757.1 1125.1 1127.7 1131.4 1142.5 1187.9 753.2 796.6 809.3 821.7 822.5 4894.8 4979.2 5100.1 5147.8 5467.3 867.6 900.1 910.1 928.5 937.5 3710.5 3794.8 3795.4 3828.3 3878.8 9330.5 9521.9 9554.9 9645.1 9648.8 9761.1 9807.2 9854.8 9862.0 9866.4 6916.7 7361.5 7447.4 7528.7 7529.0 5381.2 5417.9 5487.4 5557.8 5575.4 8841.9 9026.2 9057.7 9074.5 9121.1 8567.3 9030.2 9115.3 9136.0 9181.1 7331.2 7369.0 7503.5 7529.9 7539.1 7431.9 7470.9 7485.9 7492.5 7543.8 6613.3 6785.3 6836.3 6860.1 6870.1 6738.7 6772.7 6813.0 6866.6 6882.7 6139.8 6273.0 6277.9 6327.8 6348.7 4590.0 4628.7 4647.7 4704.4 4736.2 4160.5 4210.2 4244.5 4254.3 4294.0 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | [#4] prototype.html Cycles:180 Average:4246.34 Median:4820.39 stddev:1242.62 (25.8%) stddev-sans-first:1210.14 12:48:59 INFO - PROCESS | 3422 | Values: 291.8 299.1 305.9 306.2 312.3 4743.0 4833.9 4889.2 4987.2 5002.5 4885.5 4894.3 4913.9 4915.7 4957.3 3915.5 3924.4 3924.5 3929.9 3948.6 3617.4 3914.2 3927.2 3942.9 3963.9 3748.1 3770.6 3771.3 3785.6 3789.5 3796.5 3797.7 3805.2 3807.8 3829.6 3368.1 3370.2 3374.3 3375.9 3385.9 4697.7 4732.5 4738.5 4785.8 4806.9 4339.2 4353.7 4365.1 4374.2 4377.7 5114.8 5129.2 5150.6 5152.3 5162.7 3611.9 3644.7 3700.3 3709.0 3710.7 5336.1 5367.3 5377.9 5388.7 5390.6 3762.8 3767.4 3781.5 3789.6 3795.6 3803.0 3819.6 3824.4 3829.6 3849.1 4078.4 4268.8 4339.7 4357.8 4406.2 5098.5 5124.2 5151.1 5152.4 5180.5 1241.5 1276.3 1293.7 1300.6 1300.8 831.2 832.6 851.4 853.0 857.1 4080.2 4100.8 4107.6 4120.4 4134.6 4757.3 4759.9 4761.9 4763.5 4765.7 4090.9 4160.2 4176.5 4186.6 4197.1 2609.9 2648.6 2831.6 2841.8 2865.4 4981.2 4983.3 5011.1 5020.6 5028.7 5326.9 5427.0 5472.6 5481.2 5505.5 4947.0 4950.5 4958.2 4993.7 5012.5 5338.0 5345.1 5353.8 5363.9 5371.9 5036.4 5207.6 5221.3 5230.3 5237.6 4995.9 5004.6 5010.3 5046.6 5049.7 3731.7 3748.7 3760.2 3768.7 3769.5 5199.8 5323.8 5384.7 5419.9 5438.8 5164.8 5171.7 5178.5 5183.2 5194.7 5156.2 5157.8 5172.5 5196.9 5209.6 4914.9 4933.4 5051.9 5095.3 5099.2 5146.4 5190.9 5214.7 5217.6 5238.3 5043.1 5073.5 5074.1 5084.9 5098.5 12:48:59 INFO - PROCESS | 3422 | 12:48:59 INFO - PROCESS | 3422 | [#5] yui.html Cycles:180 Average:13428.25 Median:47979.00 stddev:15510.05 (32.3%) stddev-sans-first:15524.10 12:48:59 INFO - PROCESS | 3422 | Values: 699.9 715.2 721.9 730.9 733.5 11843.0 12153.4 12210.8 12267.0 12936.4 14267.9 14339.4 14339.5 14356.4 14364.1 794.8 801.7 806.4 806.5 807.5 635.3 772.8 799.2 799.3 801.1 822.7 824.5 825.8 826.1 827.0 815.8 820.4 821.4 822.1 825.3 596.4 597.2 597.3 600.4 604.7 12057.5 12187.4 12220.3 12221.6 12228.4 12105.9 12401.4 12452.1 12631.2 12651.6 9741.5 9783.7 9884.6 9939.6 10048.7 3265.1 3266.2 3295.9 3296.6 3307.6 47863.0 48095.0 48156.3 48345.5 48408.0 9403.0 9574.7 9671.5 9694.2 9754.4 16950.7 17146.8 17155.2 17177.7 17201.7 6330.0 6334.6 6516.3 6536.2 6550.4 3509.0 3624.7 3705.9 3757.6 3758.5 2917.8 3003.5 3010.5 3026.6 3027.5 496.5 511.9 519.5 522.8 527.1 608.3 627.1 635.7 664.5 665.1 8426.5 8509.7 8553.8 8613.3 9023.9 505.2 528.2 563.3 642.0 643.3 4005.3 4037.9 4092.9 4162.5 4185.9 54077.0 56535.0 56955.4 57171.0 57334.0 48915.5 50455.7 50829.4 50859.5 51262.2 46227.0 46428.5 46534.3 46784.8 47103.5 7805.1 7891.3 8009.4 8055.1 8142.4 31571.2 31983.2 31991.0 32146.5 32308.4 36240.3 38258.2 41135.2 41193.8 41866.8 10796.2 10966.3 10995.9 11209.7 11432.3 12059.6 12380.2 12392.2 12435.1 12537.0 11363.8 11812.4 12063.5 12102.7 12423.4 11750.9 11784.4 11934.5 11941.0 12133.0 11441.9 11689.9 11773.6 11794.8 11827.2 9524.8 9608.1 9626.8 9660.3 9678.3 9823.2 10092.7 10210.6 10268.7 10281.8 12:48:59 INFO - PROCESS | 3422 | -------- Summary: end -------- 12:48:59 INFO - PROCESS | 3422 | 12:49:00 INFO - TEST-INFO | 3422: exit 0 12:49:00 INFO - TEST-OK | dromaeo_css | took 1117013ms 12:49:00 INFO - TEST-START | kraken 12:49:00 INFO - operating with platform_type : linux_ 12:49:00 INFO - Initialising browser for kraken test... 12:49:00 INFO - TEST-INFO | started process 3658 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqLSs2h/profile http://localhost:52170/getInfo.html) 12:49:05 INFO - PROCESS | 3658 | __metrics Screen width/height:1600/1200 12:49:05 INFO - PROCESS | 3658 | colorDepth:24 12:49:05 INFO - PROCESS | 3658 | Browser inner width/height: 1024/697 12:49:05 INFO - PROCESS | 3658 | __metrics 12:49:06 ERROR - PROCESS | 3658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 12:49:07 INFO - TEST-INFO | 3658: exit 0 12:49:07 INFO - Browser initialized. 12:49:07 INFO - Running cycle 1/1 for kraken test... 12:49:07 INFO - TEST-INFO | started process 3781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqLSs2h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:19 INFO - PROCESS | 3781 | RSS: Main: 168321024 12:49:19 INFO - PROCESS | 3781 | 12:49:21 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52170/tests/kraken/driver.html?testName=audio-beat-detection) 12:49:22 INFO - PROCESS | 3781 | RSS: Main: 173228032 12:49:22 INFO - PROCESS | 3781 | 12:49:25 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52170/tests/kraken/driver.html?testName=audio-dft) 12:49:26 INFO - PROCESS | 3781 | RSS: Main: 173678592 12:49:26 INFO - PROCESS | 3781 | 12:49:28 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52170/tests/kraken/driver.html?testName=audio-fft) 12:49:29 INFO - PROCESS | 3781 | RSS: Main: 167546880 12:49:29 INFO - PROCESS | 3781 | 12:49:31 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52170/tests/kraken/driver.html?testName=audio-oscillator) 12:49:32 INFO - PROCESS | 3781 | RSS: Main: 167989248 12:49:32 INFO - PROCESS | 3781 | 12:49:34 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52170/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:35 INFO - PROCESS | 3781 | RSS: Main: 168312832 12:49:35 INFO - PROCESS | 3781 | 12:49:38 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52170/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:39 INFO - PROCESS | 3781 | RSS: Main: 168853504 12:49:39 INFO - PROCESS | 3781 | 12:49:42 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52170/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:43 INFO - PROCESS | 3781 | RSS: Main: 166789120 12:49:43 INFO - PROCESS | 3781 | 12:49:46 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52170/tests/kraken/driver.html?testName=json-parse-financial) 12:49:46 INFO - PROCESS | 3781 | RSS: Main: 168218624 12:49:46 INFO - PROCESS | 3781 | 12:49:48 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52170/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:49:49 INFO - PROCESS | 3781 | RSS: Main: 168607744 12:49:49 INFO - PROCESS | 3781 | 12:49:51 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:49:51 INFO - PROCESS | 3781 | RSS: Main: 168714240 12:49:51 INFO - PROCESS | 3781 | 12:49:53 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:49:55 INFO - PROCESS | 3781 | RSS: Main: 168882176 12:49:55 INFO - PROCESS | 3781 | 12:49:57 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:49:58 INFO - PROCESS | 3781 | RSS: Main: 168861696 12:49:58 INFO - PROCESS | 3781 | 12:50:01 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:50:01 INFO - PROCESS | 3781 | RSS: Main: 168861696 12:50:01 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | Cycle 1(1): loaded http://localhost:52170/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52170/tests/kraken/driver.html?testName=ai-astar) 12:50:03 INFO - PROCESS | 3781 | RSS: Main: 169922560 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | __start_tp_report 12:50:03 INFO - PROCESS | 3781 | _x_x_mozilla_page_load 12:50:03 INFO - PROCESS | 3781 | _x_x_mozilla_page_load_details 12:50:03 INFO - PROCESS | 3781 | |i|pagename|runs| 12:50:03 INFO - PROCESS | 3781 | |0;ai-astar;114;109;115;115;115;115;115;114;114;113 12:50:03 INFO - PROCESS | 3781 | |1;audio-beat-detection;159;135;164;177;133;152;154;139;192;134 12:50:03 INFO - PROCESS | 3781 | |2;audio-dft;164;163;165;174;162;163;164;161;162;163 12:50:03 INFO - PROCESS | 3781 | |3;audio-fft;78;75;76;153;76;76;77;76;177;77 12:50:03 INFO - PROCESS | 3781 | |4;audio-oscillator;85;88;151;86;86;85;138;86;85;138 12:50:03 INFO - PROCESS | 3781 | |5;imaging-gaussian-blur;117;117;123;118;122;117;117;118;118;118 12:50:03 INFO - PROCESS | 3781 | |6;imaging-darkroom;146;146;146;151;146;147;146;156;149;148 12:50:03 INFO - PROCESS | 3781 | |7;imaging-desaturate;95;95;95;96;96;96;96;95;95;96 12:50:03 INFO - PROCESS | 3781 | |8;json-parse-financial;68;66;63;62;64;63;67;63;63;67 12:50:03 INFO - PROCESS | 3781 | |9;json-stringify-tinderbox;68;68;67;68;67;67;67;68;69;68 12:50:03 INFO - PROCESS | 3781 | |10;stanford-crypto-aes;83;83;86;88;84;86;85;85;110;85 12:50:03 INFO - PROCESS | 3781 | |11;stanford-crypto-ccm;137;141;133;136;136;142;157;134;142;143 12:50:03 INFO - PROCESS | 3781 | |12;stanford-crypto-pbkdf2;171;177;169;170;167;171;177;186;166;170 12:50:03 INFO - PROCESS | 3781 | |13;stanford-crypto-sha256-iterative;69;70;71;66;68;66;70;67;71;69 12:50:03 INFO - PROCESS | 3781 | __end_tp_report 12:50:03 INFO - PROCESS | 3781 | __start_cc_report 12:50:03 INFO - PROCESS | 3781 | _x_x_mozilla_cycle_collect,976 12:50:03 INFO - PROCESS | 3781 | __end_cc_report 12:50:03 INFO - PROCESS | 3781 | __startTimestamp1464724203684__endTimestamp 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | ------- Summary: start ------- 12:50:03 INFO - PROCESS | 3781 | Number of tests: 14 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:1.85 (1.6%) stddev-sans-first:1.96 12:50:03 INFO - PROCESS | 3781 | Values: 114.0 109.0 115.0 115.0 115.0 115.0 115.0 114.0 114.0 113.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#1] audio-beat-detection Cycles:10 Average:153.90 Median:156.50 stddev:19.80 (12.7%) stddev-sans-first:20.92 12:50:03 INFO - PROCESS | 3781 | Values: 159.0 135.0 164.0 177.0 133.0 152.0 154.0 139.0 192.0 134.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#2] audio-dft Cycles:10 Average:164.10 Median:163.50 stddev:3.67 (2.2%) stddev-sans-first:3.89 12:50:03 INFO - PROCESS | 3781 | Values: 164.0 163.0 165.0 174.0 162.0 163.0 164.0 161.0 162.0 163.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#3] audio-fft Cycles:10 Average:94.10 Median:76.00 stddev:37.80 (49.7%) stddev-sans-first:39.64 12:50:03 INFO - PROCESS | 3781 | Values: 78.0 75.0 76.0 153.0 76.0 76.0 77.0 76.0 177.0 77.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:85.50 stddev:27.52 (32.2%) stddev-sans-first:28.43 12:50:03 INFO - PROCESS | 3781 | Values: 85.0 88.0 151.0 86.0 86.0 85.0 138.0 86.0 85.0 138.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:118.00 stddev:2.17 (1.8%) stddev-sans-first:2.24 12:50:03 INFO - PROCESS | 3781 | Values: 117.0 117.0 123.0 118.0 122.0 117.0 117.0 118.0 118.0 118.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:147.50 stddev:3.25 (2.2%) stddev-sans-first:3.35 12:50:03 INFO - PROCESS | 3781 | Values: 146.0 146.0 146.0 151.0 146.0 147.0 146.0 156.0 149.0 148.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 12:50:03 INFO - PROCESS | 3781 | Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:2.17 (3.3%) stddev-sans-first:1.92 12:50:03 INFO - PROCESS | 3781 | Values: 68.0 66.0 63.0 62.0 64.0 63.0 67.0 63.0 63.0 67.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 12:50:03 INFO - PROCESS | 3781 | Values: 68.0 68.0 67.0 68.0 67.0 67.0 67.0 68.0 69.0 68.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:85.00 stddev:8.05 (9.5%) stddev-sans-first:8.37 12:50:03 INFO - PROCESS | 3781 | Values: 83.0 83.0 86.0 88.0 84.0 86.0 85.0 85.0 110.0 85.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:141.50 stddev:6.94 (4.9%) stddev-sans-first:7.26 12:50:03 INFO - PROCESS | 3781 | Values: 137.0 141.0 133.0 136.0 136.0 142.0 157.0 134.0 142.0 143.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:171.00 stddev:6.00 (3.5%) stddev-sans-first:6.35 12:50:03 INFO - PROCESS | 3781 | Values: 171.0 177.0 169.0 170.0 167.0 171.0 177.0 186.0 166.0 170.0 12:50:03 INFO - PROCESS | 3781 | 12:50:03 INFO - PROCESS | 3781 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.50 stddev:1.89 (2.7%) stddev-sans-first:2.00 12:50:03 INFO - PROCESS | 3781 | Values: 69.0 70.0 71.0 66.0 68.0 66.0 70.0 67.0 71.0 69.0 12:50:03 INFO - PROCESS | 3781 | -------- Summary: end -------- 12:50:03 INFO - PROCESS | 3781 | 12:50:04 INFO - TEST-INFO | 3781: exit 0 12:50:04 INFO - TEST-OK | kraken | took 64100ms 12:50:04 INFO - SUITE-END | took 1181s 12:50:04 INFO - Completed test suite (00:19:41) 12:50:04 INFO - javascript benchmark 12:50:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6191.665959196748, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2674.050711997241, 2696.9212315073974, 2707.9729202707967, 2711.7288271172883, 2717.225590706648, 4657.2082745933185, 4690.1792186367375, 4694.554017368353, 4752.928706069411, 4810.4708482067035, 4686.55477729616, 4729.763511824403, 4765.571098601125, 4770.4513980892125, 4775.403074615673, 4276.294411422112, 4303.591158839921, 4350.78246087696, 4398.164348773738, 4400.185965596359, 4092.8152619305815, 4444.133393988173, 4456.041950980539, 4471.374007638933, 4478.305862591304, 4291.399204111427, 4343.782810859444, 4346.521882592944, 4360.08438227971, 4441.0, 4309.461317335333, 4323.567643235683, 4324.027093903878, 4324.200022995725, 4359.716618419792, 4353.216421044211, 4356.237658409766, 4366.934495982563, 4372.628326592244, 4402.8679139625865, 4648.837290694843, 4668.696534725266, 4688.624910007191, 4728.338032675429, 4734.289856521515, 5118.155504341779, 5129.230615407681, 5142.871428214287, 5151.201563757586, 5182.32629758129, 4991.376077990251, 5147.202183661539, 5174.637775355726, 5174.793008279665, 5178.741062946838, 4739.336492890998, 4761.571458568711, 4780.1873681474235, 4804.8318308859025, 4804.99095190014, 5230.843074707765, 5231.29377534028, 5234.057869583511, 5261.421243663194, 5286.947130528645, 4937.950620493821, 4976.0, 5030.0, 5046.343975283263, 5068.391792984866, 5065.821696670393, 5071.746412679351, 5081.974590127025, 5090.0, 5148.485151484896, 4984.077945580079, 5015.924761128586, 5017.347744793226, 5020.422651395136, 5078.7206703631655, 4900.730459824744, 5030.547250747377, 5033.19468884984, 5059.544640982255, 5070.797168113234, 4382.05785756064, 4398.252297109499, 4411.360352748898, 4442.88927768058, 4456.866294011184, 3807.2575847709986, 3829.272438236726, 3846.0384903774057, 3871.4192871069004, 3876.612338766101, 4345.044090300129, 4388.122375524844, 4407.140607581555, 4453.1316393303, 4470.307102399133, 5081.339425874613, 5097.235414687826, 5104.05574968632, 5121.795128194905, 5155.845324640267, 4400.867973960786, 4404.031113155101, 4407.140607581491, 4440.822367105344, 4447.777611119431, 4523.140603285366, 4524.343970124313, 4528.6150677192145, 4560.521145279764, 4563.155816174019, 4751.83368582098, 4759.214729569582, 4761.2382018876815, 4761.952380476151, 4804.615630749601, 5112.79548818058, 5115.948840511547, 5140.46025167352, 5140.537351638221, 5189.532942035235, 4734.479207287127, 4799.616030717604, 4803.78382972774, 4845.5881250094135, 4847.297141914332, 5092.643514954066, 5099.592032637306, 5106.897862042812, 5110.66780659256, 5151.5363617274625, 4924.679895806761, 5178.223266510054, 5181.145111056633, 5190.377154741455, 5191.402988656202, 4851.830185943475, 4889.242167464049, 4982.227754698028, 5014.6991180529285, 5018.4730603286125, 4914.631402644744, 4978.925316120189, 4982.626303027215, 4990.5508504234795, 5019.573336266459, 4743.881402964954, 4869.610431165569, 4928.236123400879, 4928.654994150375, 4932.358793356841, 4957.4794646561595, 4991.750412479434, 5012.799488020439, 5016.1221786188, 5029.346184996073, 4873.561379475864, 4902.754862256944, 4917.311576379238, 4922.212446008622, 4932.950670493393], "value": 4682.5309296081905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.4149509027559, 587.9882402351952, 594.622414766623, 604.1330690459189, 605.2313561776543, 28158.155255342346, 40544.189116217654, 41109.38335924956, 41617.375739363895, 45236.547634523646, 42199.0, 42688.78655606725, 42753.14493710124, 42845.57154428455, 42959.0, 7620.96189519053, 7748.108967468757, 7769.145394006655, 7828.765137045898, 7833.412494062968, 7537.585432801194, 7721.845563088735, 7739.342155916754, 7741.419393545479, 7754.06951165858, 7701.576413297266, 7704.730334438295, 7715.035620547432, 7717.228277172294, 7765.7670269892, 7694.999999999972, 7695.268949449794, 7735.110462296851, 7740.496867703581, 7754.495957762728, 7589.392848572101, 7613.733519326797, 7684.3084122429245, 7686.385089192851, 7696.230376962259, 38979.41530877039, 39421.80289098565, 39431.0, 39469.210615787524, 39486.21027579461, 3040.443257305691, 3173.397054559626, 3349.581302337208, 3604.279144171176, 3688.483612294281, 28128.0, 29644.407111857858, 29991.0, 30211.244718881982, 30795.846020769754, 3568.660977207161, 3622.5652921649307, 3648.361536731088, 3650.817459127033, 3674.706023518112, 266831.0, 267583.0, 268582.0, 268953.0, 269916.0, 32485.0, 50312.74218144503, 50435.24347134796, 50650.746746266035, 50699.23951140736, 33146.17134571655, 34928.65071349254, 34935.0, 34938.82530587331, 34977.47533786995, 12172.330521821385, 12193.39033048344, 12221.511139554495, 12284.201526900722, 12306.876931230752, 11709.765804683859, 11804.0, 11833.171677982458, 11836.763264734656, 11873.109516786275, 8000.279974802295, 8034.35725141999, 8035.959820200862, 8056.959715201387, 8092.473989190801, 3101.395227930577, 3108.9067327980197, 3128.765342599314, 3148.937021259562, 3178.8410579470933, 875.798566329735, 942.3497786527274, 976.3946353261024, 996.9252306076928, 1066.3974854207347, 28888.277793055342, 29005.0, 29142.854285728437, 29211.415771684446, 29470.263243418656, 921.8663293822358, 976.2677991506371, 977.9315447918719, 1032.808930347873, 1059.89930956559, 14977.925110374379, 15219.467318643794, 15222.771658425132, 15389.153596552296, 15482.22588870552, 391382.0, 391840.0, 393113.0, 393624.0, 394891.0, 51441.0, 51785.48214517882, 51799.0, 51946.0, 52083.57289630348, 1211.4669545400172, 1220.4873952939786, 1253.5424570032055, 1258.1381753498883, 1272.8663490333568, 26861.731382686703, 28425.715742843135, 28476.0, 28565.714342857136, 28581.42837143287, 41820.79089604533, 42067.57932420637, 42330.1533969325, 42373.36439953341, 42458.240214385965, 330112.0, 334575.0, 334587.0, 334746.0, 335247.0, 25747.742522575285, 25778.226653200432, 26191.607125893508, 26609.201723948314, 26666.06668766584, 43577.0, 44053.55946440495, 44402.11195776002, 44429.555704442544, 44904.77547612241, 36875.63124368722, 36974.6302536982, 37016.0, 37437.0, 37457.81271093627, 34537.827310863286, 36165.819170903975, 36337.454938176474, 36391.818040910686, 36499.0, 33284.1678958028, 35838.13294936451, 37165.0, 37475.625243747214, 37966.62033379631, 33246.0, 34183.145421364665, 34762.478562822085, 34855.30289394147, 34991.475127873615, 26315.0, 26390.0, 26532.97878721244, 26762.92948282047, 26933.192004239903], "value": 16827.0260884892, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.51043154330915, 379.7303914220903, 380.78485655604584, 387.0748910104848, 393.79719444486096, 15188.696226075472, 16114.838851611481, 16892.577685557866, 16920.661586768256, 16950.576235594115, 12343.629691109281, 14636.560903172895, 16847.0, 16955.067471289076, 17048.147592620382, 4703.129920964615, 4829.806807727704, 4858.878528036809, 4860.684055536397, 4883.853484395474, 3440.7591468597207, 5323.494268044529, 5618.241537392461, 5618.4662457066515, 5666.858328541778, 5415.918761218585, 5634.661920284776, 5641.294838145232, 5642.74607642655, 5664.150377443396, 5539.889202215933, 5556.16657501378, 5582.27430434043, 5583.246261754672, 5596.944030559704, 5241.9737901310245, 5390.407055223922, 5396.298481197458, 5405.162199859028, 5506.972465137689, 12350.382480876011, 12401.0, 12403.441845116991, 12427.75144497114, 12531.874681253163, 1740.7562941188244, 1803.7204233343853, 1809.7749700771337, 1835.3392778599828, 1935.1968932892833, 8751.693690720795, 9134.543272836398, 9179.26565874735, 9251.444913305224, 9305.39514931534, 1660.286076986895, 1711.7945846498376, 1729.619483713581, 1738.452387497934, 1752.3253547384222, 65302.67348663274, 66340.6682966587, 66698.3330166697, 67524.32475675229, 68139.65930170366, 9781.510924453749, 9793.265505087147, 10124.0, 10381.63664271762, 10424.739381515523, 14248.073875198079, 14282.21447820359, 14356.784648230285, 14401.855981440052, 14464.783028254584, 7706.152323244439, 7761.8447631047065, 7788.84422311562, 7810.570418626917, 7971.880421793678, 7402.185759566443, 7493.85012299751, 7614.505057171266, 7653.272939070779, 7664.846703065908, 4290.914181716348, 4398.670099742532, 4406.074724307931, 4508.706934049276, 4544.909101818012, 1688.3584237989485, 1731.0998280893864, 1872.850171986238, 1887.5564242403025, 1907.3419670213755, 410.51969196040704, 413.86342506972625, 419.15958503200886, 830.3481766813023, 895.4045559702855, 10450.16398688103, 10474.895251047545, 10690.358578485315, 10695.197860160519, 10785.946070269598, 387.5078650114339, 390.6874500399669, 417.8934371735188, 422.2800125785528, 425.60418810505905, 5580.414056524007, 5696.3733989261145, 5706.600537962386, 5805.941940580625, 5821.767129314865, 381828.0, 381917.0, 382432.0, 384549.0, 391478.0, 79854.20145798584, 80252.0, 80715.59642201751, 80791.0, 81109.0, 401.684677528139, 405.5376870367724, 406.96744260459093, 408.61998341542653, 409.8483561082419, 9567.330286879851, 9605.663801766905, 9758.609655613696, 9762.730844990107, 9899.109080182818, 34454.48318275178, 34479.4828077584, 34647.82676086603, 34750.47874281938, 35814.64185358113, 49254.75372623257, 49453.25820112629, 50207.24689129593, 50456.0, 50574.24138637997, 7420.0, 7600.923990760021, 7768.61156942162, 7807.843843123219, 7913.643886025257, 8349.03986041613, 9061.68284110053, 10270.794584108127, 10644.254902157085, 11352.545898164311, 9021.413608115452, 9415.811683766424, 9445.811083778424, 9474.763130921781, 9993.054826895239, 10322.277440579388, 10351.844722329322, 10426.478676066317, 10502.579896804042, 10514.947425262824, 9677.080677335642, 9784.168345690412, 9841.557129929028, 9873.210143188384, 9891.060349266809, 7657.808554786175, 7679.769606911802, 7834.334081603128, 8054.5569993650915, 8089.191080891864, 7547.245275472409, 8797.780055498664, 8969.7757556059, 9048.728538143867, 9084.727458176265], "value": 7277.5642587573075, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.3777219137482, 1444.4438891026955, 1535.2860919672357, 1723.5174151237659, 1740.7475915992175, 6910.654467276641, 7519.774406767792, 7554.660040298186, 7983.241592048754, 7983.640736166872, 7563.621818909046, 7749.573773442458, 7781.144074151839, 7815.882761758564, 7867.960660196705, 2214.7231596050533, 2257.7742225777374, 2259.243153543565, 2260.513989492259, 2262.8189744820456, 2202.7136472258585, 2228.565429741201, 2265.478939843837, 2267.8979445924974, 2271.068861766676, 2210.9336719898347, 2220.855644383118, 2224.443889027743, 2234.5419189066283, 2267.036509483471, 2135.9145634174693, 2146.6994620753107, 2151.268568686654, 2157.2665293800183, 2163.3942496101117, 2106.820920221783, 2112.7359080114984, 2114.016982103329, 2114.376259003582, 2114.503091773432, 7825.7260995865445, 7845.607719614053, 7883.132855385903, 7941.086775020831, 7944.0467143942515, 4514.277715565494, 4622.098690755304, 4636.536346365337, 4641.907161856778, 4645.907081858344, 2909.636295463067, 2997.565353023821, 3081.4915538936048, 3087.120170751325, 3099.9535006974916, 1003.4932359158652, 1040.1834559870465, 1046.5918291866178, 1047.3140093238865, 1056.6037735849056, 10683.0, 10686.038256556869, 10692.62575809843, 10698.51856666452, 10745.892541074567, 4083.448734420815, 4140.192662430857, 4212.789360531961, 4231.830726770895, 4283.571642835692, 8948.642054317885, 8975.775605609912, 8992.415492992934, 8994.50530220831, 9002.0, 4619.653525985565, 4627.051454451829, 4681.040386720714, 4684.391029166255, 4690.249560070372, 3811.447340135665, 4175.603317684815, 4265.445492086071, 4278.229918614679, 4305.07440900208, 3639.8180090995875, 3651.685393258425, 3693.076730817296, 3722.6091260417807, 3757.0795155186606, 1125.0605744203517, 1127.6504283672089, 1131.3833960491552, 1142.457332766929, 1187.8930896219208, 753.1978442958232, 796.5539297993142, 809.340387584117, 821.7452589697212, 822.4653974916353, 4894.828680996148, 4979.153543897546, 5100.0819852426175, 5147.7683504242395, 5467.289252397242, 867.5965675960582, 900.0729248873671, 910.0808183734476, 928.535732133933, 937.5359197197295, 3710.4990826238654, 3794.829232684536, 3795.4306853972125, 3828.2917660232947, 3878.8254528545717, 9330.486823224652, 9521.857172142423, 9554.904450955402, 9645.131938125602, 9648.807023859483, 9761.072698093813, 9807.215422766161, 9854.753631159278, 9862.0, 9866.358686685342, 6916.6541672916155, 7361.48469607133, 7447.441441891771, 7528.661210245552, 7529.0, 5381.246625472515, 5417.945820541901, 5487.368952570506, 5557.805476808292, 5575.386707462257, 8841.911580884365, 9026.232770214605, 9057.68298109563, 9074.500902450429, 9121.133492318218, 8567.314614830673, 9030.232430243503, 9115.316351273548, 9135.995040545402, 9181.127792859668, 7331.156916954618, 7369.0, 7503.512271702322, 7529.924700752922, 7539.0576177977755, 7431.925680743339, 7470.8505829882015, 7485.925140748523, 7492.512986656068, 7543.7736867894055, 6613.27254002069, 6785.321467853175, 6836.282190370139, 6860.073890024983, 6870.141232345957, 6738.663066846736, 6772.695228714621, 6813.0461735358085, 6866.553674011173, 6882.724691012303, 6139.84650383744, 6273.027680709315, 6277.905831412441, 6327.841803954754, 6348.682565871781, 4589.954100458953, 4628.652851036119, 4647.744374059459, 4704.411948506437, 4736.2185239436485, 4160.521540022936, 4210.221109094828, 4244.511881133709, 4254.298040823353, 4293.95706042948], "value": 4189.695501660222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.7665867306153, 299.0922550060566, 305.9066984569707, 306.19470791817514, 312.2552711782398, 4743.0, 4833.903321933559, 4889.168841296979, 4987.154752741486, 5002.5247601477895, 4885.462599114095, 4894.265860120975, 4913.852584422464, 4915.729634870081, 4957.281194226835, 3915.5105611798526, 3924.3917192835083, 3924.450576919219, 3929.9017524561973, 3948.644621984021, 3617.385044542421, 3914.178022615254, 3927.2145570885796, 3942.8619998300064, 3963.9405408918888, 3748.11919198988, 3770.5852356240794, 3771.2646034023237, 3785.5835858055593, 3789.450529673196, 3796.52543432071, 3797.6771974382073, 3805.2009078093356, 3807.7905715185793, 3829.636184562462, 3368.073779710575, 3370.2416956184907, 3374.2913988062537, 3375.8649654014057, 3385.9492107618407, 4697.694649847783, 4732.503087175832, 4738.502457241974, 4785.832495862628, 4806.870385459414, 4339.197248509005, 4353.738775673438, 4365.105153443536, 4374.168907907488, 4377.671674624416, 5114.820981265675, 5129.230615407681, 5150.587952963754, 5152.252923326097, 5162.741862906877, 3611.9097022574383, 3644.7084233261276, 3700.3154416433044, 3708.9586539038, 3710.666040056381, 5336.119540275849, 5367.275417818622, 5377.865553361197, 5388.73056347185, 5390.649607775482, 3762.793046382475, 3767.3972164453558, 3781.489498917611, 3789.6399842015517, 3795.6394142556414, 3802.961970380316, 3819.579846216914, 3824.4454554089502, 3829.5978922213326, 3849.1147036181274, 4078.4494093297617, 4268.786560671954, 4339.652827773771, 4357.8256869724855, 4406.228909940747, 5098.46466121052, 5124.15451450514, 5151.072806894795, 5152.433232344439, 5180.507851754077, 1241.490988694631, 1276.304414094321, 1293.683047653331, 1300.6098170548798, 1300.752856957175, 831.1895901495993, 832.6003518311245, 851.421033697092, 852.953087580189, 857.0786404615063, 4080.163566468867, 4100.753954762724, 4107.589241075928, 4120.402541631447, 4134.64855487281, 4757.286407038902, 4759.9052217989365, 4761.880952976203, 4763.54746299101, 4765.713257420477, 4090.9181816363507, 4160.2303573838935, 4176.4570605821045, 4186.5604111568455, 4197.097624010852, 2609.8956041758115, 2648.55653668751, 2831.603575499432, 2841.8152820067044, 2865.369618683886, 4981.153203955264, 4983.277424773388, 5011.1481048221885, 5020.648554601143, 5028.723420211851, 5326.893462130735, 5426.988021647944, 5472.589555783332, 5481.232627432163, 5505.476979686923, 4946.975265123651, 4950.455449900508, 4958.206686930074, 4993.675411098267, 5012.523810238022, 5338.0, 5345.102488616641, 5353.78584856617, 5363.892722145457, 5371.919421208763, 5036.420811606712, 5207.635465517377, 5221.321228240305, 5230.320058392384, 5237.607179461479, 4995.900081998412, 5004.599632029502, 5010.348654674869, 5046.59627229828, 5049.671771334852, 3731.6641502264924, 3748.744170702845, 3760.247950409874, 3768.736188466781, 3769.4534292526882, 5199.792008319776, 5323.8287576733055, 5384.730763461889, 5419.891602167856, 5438.830651409808, 5164.7934082636275, 5171.689698618095, 5178.533931946143, 5183.222516622386, 5194.662346947436, 5156.246876062157, 5157.819476318311, 5172.482751724798, 5196.907839835292, 5209.583233341248, 4914.926276105933, 4933.358663373739, 5051.87370315745, 5095.312132862165, 5099.22092459603, 5146.382434107931, 5190.948090519047, 5214.739263036757, 5217.634765566374, 5238.292830467991, 5043.067032598981, 5073.492650734897, 5074.122574647404, 5084.923726144185, 5098.541131298052], "value": 3849.63284747328, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.870523953069, 715.2239819795525, 721.8989341492193, 730.9122905251369, 733.5305404541093, 11843.0, 12153.392330383489, 12210.816837747441, 12267.018638508931, 12936.353182340892, 14267.857321426782, 14339.354729037192, 14339.498117565887, 14356.425742970294, 14364.066335688172, 794.7814351053448, 801.683335082642, 806.399232571732, 806.4556424413527, 807.4630370803438, 635.3043417457876, 772.8377040821434, 799.2487062161549, 799.2806474173233, 801.0627565748059, 822.6873787960567, 824.5052968219051, 825.8265764189528, 826.1201820061615, 826.9545175015372, 815.7675062607187, 820.4134044158455, 821.3511326052411, 822.0998007182126, 825.3273582030656, 596.4006173795351, 597.2385208858697, 597.2653636027667, 600.3756093662586, 604.6795198544778, 12057.517699292017, 12187.39063046853, 12220.266783992989, 12221.572244971472, 12228.449719762542, 12105.87894121065, 12401.441935112942, 12452.128351015432, 12631.178973366701, 12651.620451386472, 9741.464219467927, 9783.706488805346, 9884.604615815359, 9939.55272012761, 10048.698539043768, 3265.118418027143, 3266.2324353776844, 3295.86445345826, 3296.620888597794, 3307.58655168104, 47863.0, 48095.0, 48156.27765583519, 48345.516544834805, 48408.0, 9402.95298523503, 9574.712758617252, 9671.516424178762, 9694.22446204302, 9754.414735115915, 16950.745738813926, 17146.828531714524, 17155.228014739365, 17177.65644687099, 17201.7419738704, 6330.0, 6334.556581039283, 6516.2506311773805, 6536.182977127859, 6550.377714117151, 3508.9649103508636, 3624.655657712513, 3705.8517659293866, 3757.56787969387, 3758.5113935188797, 2917.795754297179, 3003.4743919813945, 3010.503266960971, 3026.5914101596445, 3027.470192716266, 496.4786973677624, 511.8976204759063, 519.4908989190615, 522.8274669359103, 527.1486549223054, 608.349066498842, 627.0719335383658, 635.6694518850172, 664.5082638847315, 665.1021121485956, 8426.4944103354, 8509.702160424355, 8553.786155346166, 8613.26787223093, 9023.864642030374, 505.15638883065367, 528.2419727690776, 563.3211979564614, 641.9518536109811, 643.2795269298416, 4005.2790497710107, 4037.9394309085387, 4092.856750013735, 4162.542120366757, 4185.916281674349, 54077.0, 56535.0, 56955.43044569584, 57171.0, 57334.0, 48915.51084489181, 50455.74772126189, 50829.42535171852, 50859.49140508621, 51262.231066534034, 46227.0, 46428.5357146431, 46534.30198547024, 46784.76607617008, 47103.52896470991, 7805.102413222552, 7891.250331218755, 8009.439339246197, 8055.113937466756, 8142.4300298978505, 31571.21071973219, 31983.200419989687, 31991.0, 32146.51780223345, 32308.35383292368, 36240.27519449649, 38258.23483530258, 41135.1772964533, 41193.794031029654, 41866.79066604647, 10796.244262901522, 10966.342019478856, 10995.945020275167, 11209.663710088711, 11432.256903301259, 12059.638210853689, 12380.195287306296, 12392.194507356993, 12435.129540932048, 12537.0, 11363.829542557034, 11812.350320732443, 12063.517459301529, 12102.697432564257, 12423.44094515767, 11750.88249117532, 11784.410779461165, 11934.462949167135, 11941.0, 12133.0, 11441.885581144083, 11689.88310116922, 11773.587924422603, 11794.76410471803, 11827.231229970024, 9524.761880953032, 9608.13526782593, 9626.759331016781, 9660.3237773358, 9678.322517423712, 9823.165030972448, 10092.747681308027, 10210.642627508001, 10268.743281418025, 10281.84577231357], "value": 6091.498323698136, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 115.0, 115.0, 115.0, 115.0, 115.0, 114.0, 114.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 135.0, 164.0, 177.0, 133.0, 152.0, 154.0, 139.0, 192.0, 134.0], "value": 153.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 165.0, 174.0, 162.0, 163.0, 164.0, 161.0, 162.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 153.0, 76.0, 76.0, 77.0, 76.0, 177.0, 77.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 151.0, 86.0, 86.0, 85.0, 138.0, 86.0, 85.0, 138.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 123.0, 118.0, 122.0, 117.0, 117.0, 118.0, 118.0, 118.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 151.0, 146.0, 147.0, 146.0, 156.0, 149.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 63.0, 62.0, 64.0, 63.0, 67.0, 63.0, 63.0, 67.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0, 68.0, 69.0, 68.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 86.0, 88.0, 84.0, 86.0, 85.0, 85.0, 110.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 133.0, 136.0, 136.0, 142.0, 157.0, 134.0, 142.0, 143.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 177.0, 169.0, 170.0, 167.0, 171.0, 177.0, 186.0, 166.0, 170.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 71.0, 66.0, 68.0, 66.0, 70.0, 67.0, 71.0, 69.0], "value": 68.7, "unit": "score"}]}]} 12:50:04 INFO - Return code: 0 12:50:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:50:04 ERROR - # TBPL SUCCESS # 12:50:04 INFO - Running post-action listener: _resource_record_post_action 12:50:04 INFO - ##### 12:50:04 INFO - ##### Finished run-tests step (success) 12:50:04 INFO - ##### 12:50:04 INFO - Running post-run listener: _resource_record_post_run 12:50:05 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 7921664; Write bytes: 447090688; Read time: 1500; Write time: 317956 12:50:05 INFO - TinderboxPrint: CPU usage
13.5% 12:50:05 INFO - TinderboxPrint: I/O read bytes / time
7,921,664 / 1,500 12:50:05 INFO - TinderboxPrint: I/O write bytes / time
447,090,688 / 317,956 12:50:05 INFO - TinderboxPrint: CPU idle
8,273.0 (86.5%) 12:50:05 INFO - TinderboxPrint: CPU user
1,232.3 (12.9%) 12:50:05 INFO - TinderboxPrint: Swap in / out
0 / 0 12:50:05 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107069440; Read time: 0; Write time: 93592 12:50:05 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 6160384; Write bytes: 340021248; Read time: 1440; Write time: 224364 12:50:05 INFO - Running post-run listener: _upload_blobber_files 12:50:05 INFO - Blob upload gear active. 12:50:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:50:05 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 12:50:05 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', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:50:05 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 Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:50:06 INFO - (blobuploader) - INFO - Open directory for files ... 12:50:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:50:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:50:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:06 INFO - (blobuploader) - INFO - Done attempting. 12:50:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:50:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:50:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:07 INFO - (blobuploader) - INFO - Done attempting. 12:50:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:50:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:50:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:11 INFO - (blobuploader) - INFO - Done attempting. 12:50:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:50:11 INFO - Return code: 0 12:50:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:50:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:50:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9a82db55fa6ee0cd9961252e2eea9dd225085e6646175bb072a1a1bb93d7adb5b4f5b3eccc4349d7cca2f3ac0bbfb0b9753697dfc0363038ce1af0211d231993", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c26734d65570aaa749c0d8d7aa1ef8c00daf0bd0f1f7dac97d3e72933f2bad5a230209ff9c02405496a816450fcd0f119dfa32706c9d6817ee356fa1504aa5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3db26323ad72d0c93019df097567a97d10651cd01aa04d006accec2beb862eb0e76dd78dc94e7040390a284a336b75cf7c0caf3971f84634f16c3fdad3d21670"} 12:50:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:50:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:50:11 INFO - Contents: 12:50:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9a82db55fa6ee0cd9961252e2eea9dd225085e6646175bb072a1a1bb93d7adb5b4f5b3eccc4349d7cca2f3ac0bbfb0b9753697dfc0363038ce1af0211d231993", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c26734d65570aaa749c0d8d7aa1ef8c00daf0bd0f1f7dac97d3e72933f2bad5a230209ff9c02405496a816450fcd0f119dfa32706c9d6817ee356fa1504aa5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3db26323ad72d0c93019df097567a97d10651cd01aa04d006accec2beb862eb0e76dd78dc94e7040390a284a336b75cf7c0caf3971f84634f16c3fdad3d21670"} 12:50:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:50:11 INFO - Copying logs to upload dir... 12:50:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:50:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.096936 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-31 12:50:11.314608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 12:50:11.317859) ========= 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=0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9a82db55fa6ee0cd9961252e2eea9dd225085e6646175bb072a1a1bb93d7adb5b4f5b3eccc4349d7cca2f3ac0bbfb0b9753697dfc0363038ce1af0211d231993", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c26734d65570aaa749c0d8d7aa1ef8c00daf0bd0f1f7dac97d3e72933f2bad5a230209ff9c02405496a816450fcd0f119dfa32706c9d6817ee356fa1504aa5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3db26323ad72d0c93019df097567a97d10651cd01aa04d006accec2beb862eb0e76dd78dc94e7040390a284a336b75cf7c0caf3971f84634f16c3fdad3d21670"} build_url:https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011117 build_url: 'https://queue.taskcluster.net/v1/task/Nv9Bi5d3QXqG_6wppkW8TA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/9a82db55fa6ee0cd9961252e2eea9dd225085e6646175bb072a1a1bb93d7adb5b4f5b3eccc4349d7cca2f3ac0bbfb0b9753697dfc0363038ce1af0211d231993", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3c26734d65570aaa749c0d8d7aa1ef8c00daf0bd0f1f7dac97d3e72933f2bad5a230209ff9c02405496a816450fcd0f119dfa32706c9d6817ee356fa1504aa5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3db26323ad72d0c93019df097567a97d10651cd01aa04d006accec2beb862eb0e76dd78dc94e7040390a284a336b75cf7c0caf3971f84634f16c3fdad3d21670"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 12:50:11.342056) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:50:11.342348) ========= 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=0333a256a5e4f052382dd180000001b1-1464722337.143572-842363687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:50:11.428435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 12:50:11.428823) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 12:50:11.429087) ========= ========= Total master_lag: 0.15 =========