builder: larch_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-050 starttime: 1464818777.82 results: success (0) buildid: 20160601123005 builduid: f954027930154870a384e2ab40fcb161 revision: 248d35aedf8866d3b5bf747324a1384aa1228d8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.822735) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.823119) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.823392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.839393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.839806) ========= 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=6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 15:06:18-- 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-06-01 15:06:18 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120296 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.974708) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:17.975026) ========= 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=6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015067 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.061702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.062051) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 248d35aedf8866d3b5bf747324a1384aa1228d8e --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 248d35aedf8866d3b5bf747324a1384aa1228d8e --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=6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 15:06:18,353 truncating revision to first 12 chars 2016-06-01 15:06:18,353 Setting DEBUG logging. 2016-06-01 15:06:18,353 attempt 1/10 2016-06-01 15:06:18,353 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/248d35aedf88?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 15:06:18,705 unpacking tar archive at: larch-248d35aedf88/testing/mozharness/ program finished with exit code 0 elapsedTime=0.706012 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.781893) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.782314) ========= script_repo_revision: 248d35aedf8866d3b5bf747324a1384aa1228d8e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.782712) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.782979) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 15:06:18.792403) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-06-01 15:06:18.792695) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] 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=6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:06:19 INFO - MultiFileLogger online at 20160601 15:06:19 in /builds/slave/test 15:06:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:06:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:06:19 INFO - {'append_to_log': False, 15:06:19 INFO - 'base_work_dir': '/builds/slave/test', 15:06:19 INFO - 'blob_upload_branch': 'Larch', 15:06:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:06:19 INFO - 'branch': 'Larch', 15:06:19 INFO - 'buildbot_json_path': 'buildprops.json', 15:06:19 INFO - 'config_files': ('talos/linux_config.py',), 15:06:19 INFO - 'default_actions': ('clobber', 15:06:19 INFO - 'read-buildbot-config', 15:06:19 INFO - 'download-and-extract', 15:06:19 INFO - 'populate-webroot', 15:06:19 INFO - 'create-virtualenv', 15:06:19 INFO - 'install', 15:06:19 INFO - 'run-tests'), 15:06:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:06:19 INFO - 'download_minidump_stackwalk': True, 15:06:19 INFO - 'download_symbols': 'ondemand', 15:06:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:06:19 INFO - 'tooltool.py': '/tools/tooltool.py', 15:06:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:06:19 INFO - '/tools/misc-python/virtualenv.py')}, 15:06:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:06:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:06:19 INFO - 'installer_path': 'installer.exe', 15:06:19 INFO - 'log_level': 'info', 15:06:19 INFO - 'log_name': 'talos', 15:06:19 INFO - 'log_to_console': True, 15:06:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:06:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:06:19 INFO - 'opt_config_files': (), 15:06:19 INFO - 'pip_index': False, 15:06:19 INFO - 'sps_profile': False, 15:06:19 INFO - 'sps_profile_interval': 0, 15:06:19 INFO - 'suite': 'dromaeojs-e10s', 15:06:19 INFO - 'system_bits': '32', 15:06:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:06:19 INFO - 'title': 'talos-linux64-ix-050', 15:06:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:06:19 INFO - 'use_talos_json': True, 15:06:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:06:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:06:19 INFO - 'work_dir': 'build'} 15:06:19 INFO - ##### 15:06:19 INFO - ##### Running clobber step. 15:06:19 INFO - ##### 15:06:19 INFO - Running pre-action listener: _resource_record_pre_action 15:06:19 INFO - Running main action method: clobber 15:06:19 INFO - rmtree: /builds/slave/test/build 15:06:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:06:19 INFO - Running post-action listener: _resource_record_post_action 15:06:19 INFO - ##### 15:06:19 INFO - ##### Finished clobber step (success) 15:06:19 INFO - ##### 15:06:19 INFO - ##### 15:06:19 INFO - ##### Running read-buildbot-config step. 15:06:19 INFO - ##### 15:06:19 INFO - Running pre-action listener: _resource_record_pre_action 15:06:19 INFO - Running main action method: read_buildbot_config 15:06:19 INFO - Using buildbot properties: 15:06:19 INFO - { 15:06:19 INFO - "project": "", 15:06:19 INFO - "product": "firefox", 15:06:19 INFO - "script_repo_revision": "production", 15:06:19 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 15:06:19 INFO - "repository": "", 15:06:19 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs-e10s", 15:06:19 INFO - "buildid": "20160601123005", 15:06:19 INFO - "pgo_build": "True", 15:06:19 INFO - "basedir": "/builds/slave/test", 15:06:19 INFO - "buildnumber": 4, 15:06:19 INFO - "slavename": "talos-linux64-ix-050", 15:06:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:06:19 INFO - "platform": "ubuntu64_hw", 15:06:19 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 15:06:19 INFO - "branch": "larch", 15:06:19 INFO - "repo_path": "projects/larch", 15:06:19 INFO - "revision": "248d35aedf8866d3b5bf747324a1384aa1228d8e", 15:06:19 INFO - "stage_platform": "linux64-pgo", 15:06:19 INFO - "builduid": "f954027930154870a384e2ab40fcb161", 15:06:19 INFO - "slavebuilddir": "test" 15:06:19 INFO - } 15:06:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:06:19 INFO - Running post-action listener: _resource_record_post_action 15:06:19 INFO - ##### 15:06:19 INFO - ##### Finished read-buildbot-config step (success) 15:06:19 INFO - ##### 15:06:19 INFO - ##### 15:06:19 INFO - ##### Running download-and-extract step. 15:06:19 INFO - ##### 15:06:19 INFO - Running pre-action listener: _resource_record_pre_action 15:06:19 INFO - Running main action method: download_and_extract 15:06:19 INFO - mkdir: /builds/slave/test/build/tests 15:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:19 INFO - https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:06:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:06:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:06:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:20 INFO - Downloaded 1361 bytes. 15:06:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:06:20 INFO - Using the following test package requirements: 15:06:20 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:06:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:06:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:06:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'jsshell-linux-x86_64.zip'], 15:06:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:06:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:06:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:06:20 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:06:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:06:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:06:20 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:06:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:06:20 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')]} 15:06:20 INFO - https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:06:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:06:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:06:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:23 INFO - Downloaded 24422267 bytes. 15:06:23 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'] 15:06:23 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 15:06:24 INFO - Return code: 0 15:06:24 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 15:06: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')]} 15:06:24 INFO - https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:06:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:06:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:06:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:25 INFO - Downloaded 24422267 bytes. 15:06:25 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'] 15:06:25 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 15:06:27 INFO - Return code: 0 15:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:27 INFO - https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:06:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:06:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:06:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:29 INFO - Downloaded 10834063 bytes. 15:06:29 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'] 15:06:29 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 15:06:29 INFO - Return code: 0 15:06: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')]} 15:06:29 INFO - https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:06:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:06:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:06:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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 15:06:31 INFO - Downloaded 59093562 bytes. 15:06:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:06:31 INFO - mkdir: /builds/slave/test/properties 15:06:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:06:31 INFO - Writing to file /builds/slave/test/properties/build_url 15:06:31 INFO - Contents: 15:06:31 INFO - build_url:https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:06:32 INFO - Running post-action listener: _resource_record_post_action 15:06:32 INFO - Running post-action listener: set_extra_try_arguments 15:06:32 INFO - ##### 15:06:32 INFO - ##### Finished download-and-extract step (success) 15:06:32 INFO - ##### 15:06:32 INFO - ##### 15:06:32 INFO - ##### Running populate-webroot step. 15:06:32 INFO - ##### 15:06:32 INFO - Running pre-action listener: _resource_record_pre_action 15:06:32 INFO - Running main action method: populate_webroot 15:06:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:06:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:06:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:06:32 INFO - '--tppagecycles', 15:06:32 INFO - '7'], 15:06:32 INFO - 'tests': ['tsvgm']}}, 15:06:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:06:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:06:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:06:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:06:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:06:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:06:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['damp', 'tps']}, 15:06:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'tests': ['damp', 'tps']}, 15:06:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['dromaeo_dom']}, 15:06:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:06:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tsvgx', 15:06:32 INFO - 'tsvgr_opacity', 15:06:32 INFO - 'tart', 15:06:32 INFO - 'tscrollx', 15:06:32 INFO - 'cart']}, 15:06:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:06:32 INFO - 'tsvgr_opacity', 15:06:32 INFO - 'tart', 15:06:32 INFO - 'tscrollx', 15:06:32 INFO - 'cart']}, 15:06:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tp5o']}, 15:06:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'tests': ['tp5o']}, 15:06:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s', 15:06:32 INFO - '--xperf_path', 15:06:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:06:32 INFO - 'tests': ['tp5n']}, 15:06:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--xperf_path', 15:06:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:06:32 INFO - 'tests': ['tp5n']}}, 15:06:32 INFO - 'talos.zip': {'path': '', 15:06:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:06:32 INFO - Running post-action listener: _resource_record_post_action 15:06:32 INFO - ##### 15:06:32 INFO - ##### Finished populate-webroot step (success) 15:06:32 INFO - ##### 15:06:32 INFO - ##### 15:06:32 INFO - ##### Running create-virtualenv step. 15:06:32 INFO - ##### 15:06:32 INFO - Running pre-action listener: _resource_record_pre_action 15:06:32 INFO - Running main action method: create_virtualenv 15:06:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:06:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:06:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:06:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:06:32 INFO - Using real prefix '/usr' 15:06:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:06:32 INFO - Installing distribute.............................................................................................................................................................................................done. 15:06:34 INFO - Installing pip.................done. 15:06:34 INFO - Return code: 0 15:06:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:06: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')]} 15:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06: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')]} 15:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:34 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:06:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:06:34 INFO - Using env: {'DISPLAY': ':0', 15:06:34 INFO - 'HOME': '/home/cltbld', 15:06:34 INFO - 'LANG': 'en_US.UTF-8', 15:06:34 INFO - 'LANGUAGE': 'en_US:en', 15:06:34 INFO - 'LOGNAME': 'cltbld', 15:06:34 INFO - 'MAIL': '/var/mail/cltbld', 15:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:34 INFO - 'MOZ_NO_REMOTE': '1', 15:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:34 INFO - 'NO_EM_RESTART': '1', 15:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:34 INFO - 'PWD': '/builds/slave/test', 15:06:34 INFO - 'SHELL': '/bin/bash', 15:06:34 INFO - 'SHLVL': '1', 15:06:34 INFO - 'TERM': 'linux', 15:06:34 INFO - 'TMOUT': '86400', 15:06:34 INFO - 'USER': 'cltbld', 15:06:34 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:34 INFO - '_': '/tools/buildbot/bin/python'} 15:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:06:34 INFO - Cleaning up... 15:06:34 INFO - Return code: 0 15:06:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:06: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')]} 15:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06: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')]} 15:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:34 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:06:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:06:34 INFO - Using env: {'DISPLAY': ':0', 15:06:34 INFO - 'HOME': '/home/cltbld', 15:06:34 INFO - 'LANG': 'en_US.UTF-8', 15:06:34 INFO - 'LANGUAGE': 'en_US:en', 15:06:34 INFO - 'LOGNAME': 'cltbld', 15:06:34 INFO - 'MAIL': '/var/mail/cltbld', 15:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:34 INFO - 'MOZ_NO_REMOTE': '1', 15:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:34 INFO - 'NO_EM_RESTART': '1', 15:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:34 INFO - 'PWD': '/builds/slave/test', 15:06:34 INFO - 'SHELL': '/bin/bash', 15:06:34 INFO - 'SHLVL': '1', 15:06:34 INFO - 'TERM': 'linux', 15:06:34 INFO - 'TMOUT': '86400', 15:06:34 INFO - 'USER': 'cltbld', 15:06:34 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:34 INFO - '_': '/tools/buildbot/bin/python'} 15:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:35 INFO - Downloading/unpacking psutil>=3.1.1 15:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:06:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:06:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:06:37 INFO - Installing collected packages: psutil 15:06:37 INFO - Running setup.py install for psutil 15:06:37 INFO - building 'psutil._psutil_linux' extension 15:06:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:06:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:06:37 INFO - building 'psutil._psutil_posix' extension 15:06:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:06:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:06:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:06:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:06:37 INFO - Successfully installed psutil 15:06:37 INFO - Cleaning up... 15:06:37 INFO - Return code: 0 15:06:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:37 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:06:37 INFO - Using env: {'DISPLAY': ':0', 15:06:37 INFO - 'HOME': '/home/cltbld', 15:06:37 INFO - 'LANG': 'en_US.UTF-8', 15:06:37 INFO - 'LANGUAGE': 'en_US:en', 15:06:37 INFO - 'LOGNAME': 'cltbld', 15:06:37 INFO - 'MAIL': '/var/mail/cltbld', 15:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:37 INFO - 'MOZ_NO_REMOTE': '1', 15:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:37 INFO - 'NO_EM_RESTART': '1', 15:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:37 INFO - 'PWD': '/builds/slave/test', 15:06:37 INFO - 'SHELL': '/bin/bash', 15:06:37 INFO - 'SHLVL': '1', 15:06:37 INFO - 'TERM': 'linux', 15:06:37 INFO - 'TMOUT': '86400', 15:06:37 INFO - 'USER': 'cltbld', 15:06:37 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:37 INFO - '_': '/tools/buildbot/bin/python'} 15:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:06:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:06:39 INFO - Installing collected packages: mozsystemmonitor 15:06:39 INFO - Running setup.py install for mozsystemmonitor 15:06:39 INFO - Successfully installed mozsystemmonitor 15:06:39 INFO - Cleaning up... 15:06:39 INFO - Return code: 0 15:06:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:39 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:06:39 INFO - Using env: {'DISPLAY': ':0', 15:06:39 INFO - 'HOME': '/home/cltbld', 15:06:39 INFO - 'LANG': 'en_US.UTF-8', 15:06:39 INFO - 'LANGUAGE': 'en_US:en', 15:06:39 INFO - 'LOGNAME': 'cltbld', 15:06:39 INFO - 'MAIL': '/var/mail/cltbld', 15:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:39 INFO - 'MOZ_NO_REMOTE': '1', 15:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:39 INFO - 'NO_EM_RESTART': '1', 15:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:39 INFO - 'PWD': '/builds/slave/test', 15:06:39 INFO - 'SHELL': '/bin/bash', 15:06:39 INFO - 'SHLVL': '1', 15:06:39 INFO - 'TERM': 'linux', 15:06:39 INFO - 'TMOUT': '86400', 15:06:39 INFO - 'USER': 'cltbld', 15:06:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:39 INFO - '_': '/tools/buildbot/bin/python'} 15:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:41 INFO - Downloading blobuploader-1.2.4.tar.gz 15:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:06:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:06:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:42 INFO - Downloading docopt-0.6.1.tar.gz 15:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:06:42 INFO - Installing collected packages: blobuploader, requests, docopt 15:06:42 INFO - Running setup.py install for blobuploader 15:06:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:06:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:06:42 INFO - Running setup.py install for requests 15:06:43 INFO - Running setup.py install for docopt 15:06:43 INFO - Successfully installed blobuploader requests docopt 15:06:43 INFO - Cleaning up... 15:06:43 INFO - Return code: 0 15:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:43 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')]} 15:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:43 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')]} 15:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:43 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:06:43 INFO - Using env: {'DISPLAY': ':0', 15:06:43 INFO - 'HOME': '/home/cltbld', 15:06:43 INFO - 'LANG': 'en_US.UTF-8', 15:06:43 INFO - 'LANGUAGE': 'en_US:en', 15:06:43 INFO - 'LOGNAME': 'cltbld', 15:06:43 INFO - 'MAIL': '/var/mail/cltbld', 15:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:43 INFO - 'MOZ_NO_REMOTE': '1', 15:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:43 INFO - 'NO_EM_RESTART': '1', 15:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:43 INFO - 'PWD': '/builds/slave/test', 15:06:43 INFO - 'SHELL': '/bin/bash', 15:06:43 INFO - 'SHLVL': '1', 15:06:43 INFO - 'TERM': 'linux', 15:06:43 INFO - 'TMOUT': '86400', 15:06:43 INFO - 'USER': 'cltbld', 15:06:43 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:43 INFO - '_': '/tools/buildbot/bin/python'} 15:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:06:43 INFO - Running setup.py (path:/tmp/pip-zvG7Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:06:43 INFO - Running setup.py (path:/tmp/pip-eSgw8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:06:43 INFO - Running setup.py (path:/tmp/pip-btoSxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:06:43 INFO - Running setup.py (path:/tmp/pip-n3_UkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:06:43 INFO - Running setup.py (path:/tmp/pip-e1mwTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:06:43 INFO - Running setup.py (path:/tmp/pip-YfM43x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:06:43 INFO - Running setup.py (path:/tmp/pip-CVXvqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:06:44 INFO - Running setup.py (path:/tmp/pip-YSZjd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:06:44 INFO - Running setup.py (path:/tmp/pip-u8VM0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:06:44 INFO - Running setup.py (path:/tmp/pip-GIfzr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:06:44 INFO - Running setup.py (path:/tmp/pip-YymqsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:06:44 INFO - Running setup.py (path:/tmp/pip-dAOFle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:06:44 INFO - Running setup.py (path:/tmp/pip-T9GAWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:06:44 INFO - Running setup.py (path:/tmp/pip-YkNAwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:44 INFO - Running setup.py (path:/tmp/pip-FhLGe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:06:44 INFO - Running setup.py (path:/tmp/pip-vvs9wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:06:44 INFO - Running setup.py (path:/tmp/pip-gpFZyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:06:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:06:44 INFO - Running setup.py install for manifestparser 15:06:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:06:44 INFO - Running setup.py install for mozcrash 15:06:44 INFO - Running setup.py install for mozdebug 15:06:45 INFO - Running setup.py install for mozdevice 15:06:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Running setup.py install for mozfile 15:06:45 INFO - Running setup.py install for mozhttpd 15:06:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Running setup.py install for mozinfo 15:06:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Running setup.py install for mozInstall 15:06:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Running setup.py install for mozleak 15:06:45 INFO - Running setup.py install for mozlog 15:06:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Running setup.py install for moznetwork 15:06:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:06:45 INFO - Running setup.py install for mozprocess 15:06:45 INFO - Running setup.py install for mozprofile 15:06:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:06:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:06:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:06:46 INFO - Running setup.py install for mozrunner 15:06:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:06:46 INFO - Running setup.py install for mozscreenshot 15:06:46 INFO - Running setup.py install for moztest 15:06:46 INFO - Running setup.py install for mozversion 15:06:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:06:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:06:46 INFO - Cleaning up... 15:06:46 INFO - Return code: 0 15:06:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:46 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')]} 15:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:46 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')]} 15:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:46 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:06:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:06:46 INFO - Using env: {'DISPLAY': ':0', 15:06:46 INFO - 'HOME': '/home/cltbld', 15:06:46 INFO - 'LANG': 'en_US.UTF-8', 15:06:46 INFO - 'LANGUAGE': 'en_US:en', 15:06:46 INFO - 'LOGNAME': 'cltbld', 15:06:46 INFO - 'MAIL': '/var/mail/cltbld', 15:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:46 INFO - 'MOZ_NO_REMOTE': '1', 15:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:46 INFO - 'NO_EM_RESTART': '1', 15:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:46 INFO - 'PWD': '/builds/slave/test', 15:06:46 INFO - 'SHELL': '/bin/bash', 15:06:46 INFO - 'SHLVL': '1', 15:06:46 INFO - 'TERM': 'linux', 15:06:46 INFO - 'TMOUT': '86400', 15:06:46 INFO - 'USER': 'cltbld', 15:06:46 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:46 INFO - '_': '/tools/buildbot/bin/python'} 15:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:06:46 INFO - Running setup.py (path:/tmp/pip-7gp7DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:06:46 INFO - Running setup.py (path:/tmp/pip-hxP78w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:06:46 INFO - Running setup.py (path:/tmp/pip-yAZTju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:06:46 INFO - Running setup.py (path:/tmp/pip-dXEFBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:06:47 INFO - Running setup.py (path:/tmp/pip-DlwL6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:06:47 INFO - Running setup.py (path:/tmp/pip-zEmOJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:06:47 INFO - Running setup.py (path:/tmp/pip-sLqFKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:06:47 INFO - Running setup.py (path:/tmp/pip-RFDYIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:06:47 INFO - Running setup.py (path:/tmp/pip-FiiDiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:06:47 INFO - Running setup.py (path:/tmp/pip-_QlfXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:06:47 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)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:06:47 INFO - Running setup.py (path:/tmp/pip-XQZNWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:06:47 INFO - Running setup.py (path:/tmp/pip-NHFe3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:06:47 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)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:06:47 INFO - Running setup.py (path:/tmp/pip-gZa1ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:06:47 INFO - Running setup.py (path:/tmp/pip-e_wsQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:06:47 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)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:47 INFO - Running setup.py (path:/tmp/pip-94QT65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:06:47 INFO - Running setup.py (path:/tmp/pip-ZQzpBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:06:47 INFO - Running setup.py (path:/tmp/pip-RpDPn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:06:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:49 INFO - Downloading blessings-1.6.tar.gz 15:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:06:49 INFO - Installing collected packages: blessings 15:06:49 INFO - Running setup.py install for blessings 15:06:50 INFO - Successfully installed blessings 15:06:50 INFO - Cleaning up... 15:06:50 INFO - Return code: 0 15:06:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:06:50 INFO - Reading from file tmpfile_stdout 15:06:50 INFO - Current package versions: 15:06:50 INFO - argparse == 1.2.1 15:06:50 INFO - blessings == 1.6 15:06:50 INFO - blobuploader == 1.2.4 15:06:50 INFO - docopt == 0.6.1 15:06:50 INFO - manifestparser == 1.1 15:06:50 INFO - mozInstall == 1.12 15:06:50 INFO - mozcrash == 0.17 15:06:50 INFO - mozdebug == 0.1 15:06:50 INFO - mozdevice == 0.48 15:06:50 INFO - mozfile == 1.2 15:06:50 INFO - mozhttpd == 0.7 15:06:50 INFO - mozinfo == 0.9 15:06:50 INFO - mozleak == 0.1 15:06:50 INFO - mozlog == 3.2 15:06:50 INFO - moznetwork == 0.27 15:06:50 INFO - mozprocess == 0.22 15:06:50 INFO - mozprofile == 0.28 15:06:50 INFO - mozrunner == 6.11 15:06:50 INFO - mozscreenshot == 0.1 15:06:50 INFO - mozsystemmonitor == 0.3 15:06:50 INFO - moztest == 0.7 15:06:50 INFO - mozversion == 1.4 15:06:50 INFO - psutil == 3.1.1 15:06:50 INFO - requests == 1.2.3 15:06:50 INFO - wsgiref == 0.1.2 15:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:50 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:06:50 INFO - Using env: {'DISPLAY': ':0', 15:06:50 INFO - 'HOME': '/home/cltbld', 15:06:50 INFO - 'LANG': 'en_US.UTF-8', 15:06:50 INFO - 'LANGUAGE': 'en_US:en', 15:06:50 INFO - 'LOGNAME': 'cltbld', 15:06:50 INFO - 'MAIL': '/var/mail/cltbld', 15:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:50 INFO - 'MOZ_NO_REMOTE': '1', 15:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:50 INFO - 'NO_EM_RESTART': '1', 15:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:50 INFO - 'PWD': '/builds/slave/test', 15:06:50 INFO - 'SHELL': '/bin/bash', 15:06:50 INFO - 'SHLVL': '1', 15:06:50 INFO - 'TERM': 'linux', 15:06:50 INFO - 'TMOUT': '86400', 15:06:50 INFO - 'USER': 'cltbld', 15:06:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:50 INFO - '_': '/tools/buildbot/bin/python'} 15:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:06:50 INFO - Cleaning up... 15:06:50 INFO - Return code: 0 15:06:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:50 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:06:50 INFO - Using env: {'DISPLAY': ':0', 15:06:50 INFO - 'HOME': '/home/cltbld', 15:06:50 INFO - 'LANG': 'en_US.UTF-8', 15:06:50 INFO - 'LANGUAGE': 'en_US:en', 15:06:50 INFO - 'LOGNAME': 'cltbld', 15:06:50 INFO - 'MAIL': '/var/mail/cltbld', 15:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:50 INFO - 'MOZ_NO_REMOTE': '1', 15:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:50 INFO - 'NO_EM_RESTART': '1', 15:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:50 INFO - 'PWD': '/builds/slave/test', 15:06:50 INFO - 'SHELL': '/bin/bash', 15:06:50 INFO - 'SHLVL': '1', 15:06:50 INFO - 'TERM': 'linux', 15:06:50 INFO - 'TMOUT': '86400', 15:06:50 INFO - 'USER': 'cltbld', 15:06:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:50 INFO - '_': '/tools/buildbot/bin/python'} 15:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:50 INFO - Downloading/unpacking jsonschema 15:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:06:52 INFO - Downloading/unpacking functools32 (from jsonschema) 15:06: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 15:06: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 15:06: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 15:06: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 15:06: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 15:06: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 15:06:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:06:52 INFO - warning: no files found matching '*.txt' 15:06:52 INFO - no previously-included directories found matching 'build' 15:06:52 INFO - no previously-included directories found matching 'dist' 15:06:52 INFO - no previously-included directories found matching '.git*' 15:06:52 INFO - Installing collected packages: jsonschema, functools32 15:06:53 INFO - Running setup.py install for functools32 15:06:53 INFO - warning: no files found matching '*.txt' 15:06:53 INFO - no previously-included directories found matching 'build' 15:06:53 INFO - no previously-included directories found matching 'dist' 15:06:53 INFO - no previously-included directories found matching '.git*' 15:06:53 INFO - Successfully installed jsonschema functools32 15:06:53 INFO - Cleaning up... 15:06:53 INFO - Return code: 0 15:06:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:06: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')]} 15:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06: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')]} 15:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:53 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 0x7ffbd08fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c05770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, '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': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:53 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 15:06:53 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 15:06:53 INFO - Using env: {'DISPLAY': ':0', 15:06:53 INFO - 'HOME': '/home/cltbld', 15:06:53 INFO - 'LANG': 'en_US.UTF-8', 15:06:53 INFO - 'LANGUAGE': 'en_US:en', 15:06:53 INFO - 'LOGNAME': 'cltbld', 15:06:53 INFO - 'MAIL': '/var/mail/cltbld', 15:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:53 INFO - 'MOZ_NO_REMOTE': '1', 15:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:53 INFO - 'NO_EM_RESTART': '1', 15:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:53 INFO - 'PWD': '/builds/slave/test', 15:06:53 INFO - 'SHELL': '/bin/bash', 15:06:53 INFO - 'SHLVL': '1', 15:06:53 INFO - 'TERM': 'linux', 15:06:53 INFO - 'TMOUT': '86400', 15:06:53 INFO - 'USER': 'cltbld', 15:06:53 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:53 INFO - '_': '/tools/buildbot/bin/python'} 15:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:53 INFO - Downloading/unpacking flake8 15:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:06:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:06:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:06:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:06:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:06:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:06:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:06:55 INFO - no previously-included directories found matching 'docs/_build' 15:06:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:06:56 INFO - Running setup.py install for pep8 15:06:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:06:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:06:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:06:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:06:56 INFO - no previously-included directories found matching 'docs/_build' 15:06:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:06:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:06:56 INFO - Cleaning up... 15:06:56 INFO - Return code: 0 15:06:56 INFO - Running post-action listener: _resource_record_post_action 15:06:56 INFO - Running post-action listener: _start_resource_monitoring 15:06:56 INFO - Starting resource monitoring. 15:06:56 INFO - ##### 15:06:56 INFO - ##### Finished create-virtualenv step (success) 15:06:56 INFO - ##### 15:06:56 INFO - ##### 15:06:56 INFO - ##### Running install step. 15:06:56 INFO - ##### 15:06:56 INFO - Running pre-action listener: _resource_record_pre_action 15:06:56 INFO - Running main action method: install 15:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:06:56 INFO - Reading from file tmpfile_stdout 15:06:56 INFO - Detecting whether we're running mozinstall >=1.0... 15:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:06:56 INFO - Reading from file tmpfile_stdout 15:06:56 INFO - Output received: 15:06:56 INFO - Usage: mozinstall [options] installer 15:06:56 INFO - Options: 15:06:56 INFO - -h, --help show this help message and exit 15:06:56 INFO - -d DEST, --destination=DEST 15:06:56 INFO - Directory to install application into. [default: 15:06:56 INFO - "/builds/slave/test"] 15:06:56 INFO - --app=APP Application being installed. [default: firefox] 15:06:56 INFO - mkdir: /builds/slave/test/build/application 15:06:56 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'] 15:06:56 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 15:07:13 INFO - Reading from file tmpfile_stdout 15:07:13 INFO - Output received: 15:07:13 INFO - /builds/slave/test/build/application/firefox/firefox 15:07:13 INFO - Running post-action listener: _resource_record_post_action 15:07:13 INFO - ##### 15:07:13 INFO - ##### Finished install step (success) 15:07:13 INFO - ##### 15:07:13 INFO - ##### 15:07:13 INFO - ##### Running run-tests step. 15:07:13 INFO - ##### 15:07:13 INFO - Running pre-action listener: _resource_record_pre_action 15:07:13 INFO - Running main action method: run_tests 15:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:07:13 INFO - Python 2.7.3 15:07:13 INFO - Return code: 0 15:07:13 INFO - grabbing minidump binary from tooltool 15:07:13 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')]} 15:07:13 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 0x1c949c0>, '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 0x1c958c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c95d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:07:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:07:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:07:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:07:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:07:13 INFO - Return code: 0 15:07:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:07:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:07:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:07:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:07:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:07:14 INFO - Return code: 0 15:07:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 15:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 15:07:14 INFO - Using env: {'DISPLAY': ':0', 15:07:14 INFO - 'HOME': '/home/cltbld', 15:07:14 INFO - 'LANG': 'en_US.UTF-8', 15:07:14 INFO - 'LANGUAGE': 'en_US:en', 15:07:14 INFO - 'LOGNAME': 'cltbld', 15:07:14 INFO - 'MAIL': '/var/mail/cltbld', 15:07:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:07:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:14 INFO - 'MOZ_NO_REMOTE': '1', 15:07:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:07:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:14 INFO - 'NO_EM_RESTART': '1', 15:07:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:14 INFO - 'PWD': '/builds/slave/test', 15:07:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:07:14 INFO - 'SHELL': '/bin/bash', 15:07:14 INFO - 'SHLVL': '1', 15:07:14 INFO - 'TERM': 'linux', 15:07:14 INFO - 'TMOUT': '86400', 15:07:14 INFO - 'USER': 'cltbld', 15:07:14 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533', 15:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:14 INFO - '_': '/tools/buildbot/bin/python'} 15:07:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 15:07:14 INFO - mozversion application_buildid: 20160601123005 15:07:14 INFO - mozversion application_changeset: 248d35aedf8866d3b5bf747324a1384aa1228d8e 15:07:14 INFO - mozversion application_display_name: Nightly 15:07:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:07:14 INFO - mozversion application_name: Firefox 15:07:14 INFO - mozversion application_remotingname: firefox 15:07:14 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:07:14 INFO - mozversion application_vendor: Mozilla 15:07:14 INFO - mozversion application_version: 49.0a1 15:07:14 INFO - mozversion platform_buildid: 20160601123005 15:07:14 INFO - mozversion platform_changeset: 248d35aedf8866d3b5bf747324a1384aa1228d8e 15:07:14 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:07:14 INFO - mozversion platform_version: 49.0a1 15:07:14 INFO - using testdate: 1464818834 15:07:14 INFO - actual date: 1464818834 15:07:14 INFO - starting webserver on 'localhost:56005' 15:07:14 INFO - SUITE-START | Running 2 tests 15:07:14 INFO - TEST-START | dromaeo_css 15:07:14 INFO - operating with platform_type : linux_ 15:07:14 INFO - Initialising browser for dromaeo_css test... 15:07:14 INFO - TEST-INFO | started process 29903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgME6vx/profile http://localhost:56005/getInfo.html) 15:07:19 INFO - PROCESS | 29903 | __metrics Screen width/height:1600/1200 15:07:19 INFO - PROCESS | 29903 | colorDepth:24 15:07:19 INFO - PROCESS | 29903 | Browser inner width/height: 1024/697 15:07:19 INFO - PROCESS | 29903 | __metrics 15:07:19 ERROR - PROCESS | 29903 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 15:07:20 INFO - TEST-INFO | 29903: exit 0 15:07:20 INFO - Browser initialized. 15:07:20 INFO - Running cycle 1/1 for dromaeo_css test... 15:07:20 INFO - TEST-INFO | started process 30025 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgME6vx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:07:32 INFO - PROCESS | 30025 | RSS: Main: 166985728 15:07:32 INFO - PROCESS | 30025 | 15:08:21 INFO - PROCESS | 30025 | 1464818901918 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:10:29 INFO - PROCESS | 30025 | Cycle 1(1): loaded http://localhost:56005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56005/tests/dromaeo/cssquery-ext.html) 15:10:30 INFO - PROCESS | 30025 | RSS: Main: 171487232 15:10:30 INFO - PROCESS | 30025 | 15:13:32 INFO - PROCESS | 30025 | Cycle 1(1): loaded http://localhost:56005/tests/dromaeo/cssquery-ext.html (next: http://localhost:56005/tests/dromaeo/cssquery-jquery.html) 15:13:33 INFO - PROCESS | 30025 | RSS: Main: 170168320 15:13:33 INFO - PROCESS | 30025 | 15:16:35 INFO - PROCESS | 30025 | Cycle 1(1): loaded http://localhost:56005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56005/tests/dromaeo/cssquery-mootools.html) 15:16:35 INFO - PROCESS | 30025 | RSS: Main: 171335680 15:16:35 INFO - PROCESS | 30025 | 15:19:37 INFO - PROCESS | 30025 | Cycle 1(1): loaded http://localhost:56005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56005/tests/dromaeo/cssquery-prototype.html) 15:19:38 INFO - PROCESS | 30025 | RSS: Main: 168996864 15:19:38 INFO - PROCESS | 30025 | 15:22:40 INFO - PROCESS | 30025 | Cycle 1(1): loaded http://localhost:56005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56005/tests/dromaeo/cssquery-yui.html) 15:22:41 INFO - PROCESS | 30025 | RSS: Main: 170790912 15:22:41 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | Cycle 1(1): loaded http://localhost:56005/tests/dromaeo/cssquery-yui.html (next: http://localhost:56005/tests/dromaeo/cssquery-dojo.html) 15:25:43 INFO - PROCESS | 30025 | RSS: Main: 172085248 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | __start_tp_report 15:25:43 INFO - PROCESS | 30025 | _x_x_mozilla_page_load 15:25:43 INFO - PROCESS | 30025 | _x_x_mozilla_page_load_details 15:25:43 INFO - PROCESS | 30025 | |i|pagename|runs| 15:25:43 INFO - PROCESS | 30025 | |0;dojo.html;3154.3375891062874;3171.8096914185166;3184.4108839864625;3188.9362212755746;3192.393445245403;5336.6531175473565;5453.454654534545;5461.563074954004;5490.862728431781;5523.751431185597;5392.299001129848;5479.424660410659;5482.177673349;5485.670859748418;5570.387257401682;4990.47600002;5037.899242015157;5068.1890897456415;5121.462246464123;5165.9225111623355;774.4271128512673;4880.292357608145;4930.235813448921;5053.014662140884;5066.721330326849;4902.926456103161;4914.04176185644;4934.925976110343;4960.23116416956;4981.252812078199;4966.081274964144;4967.900641987176;4970.627202959792;4981.651284410093;4995.875103122415;4913.680610760289;4991;5029.572486338666;5035.798568057273;5041.067402530543;5398.973005134949;5435.483629055233;5443.945560544423;5450.100733378988;5479.205515200274;5636.802711905105;5656.264685590888;5917.082852157922;5920.2895652521975;5948.524118070544;5791.913121303184;5835.241418615596;5846.093855452412;5876.177335173079;5879.147523609053;5450.945490545084;5484.149956756709;5484.424135465759;5486.286782718261;5515.283013208297;5843.357230704619;5846.824595262149;5848.2689663792025;5849.3858144894175;5896.941030589725;5706.572007099484;5788.0739081746715;5791.276090488689;5793.362730099686;5800.6809625471005;5866.178734977107;5869.970650146722;5871.1486834409625;5880.529557635379;5880.794172203952;5509.724513774296;5797.507211886952;5821.621594596338;5826.329972053183;5836.737346819318;5666.744996475169;5681.2330335404195;5690.9146362804595;5711.029125048752;5731.398203188689;4368.060866913629;4391.363252328394;4396.230659634615;4405.3612226227015;4429.689921705514;3862.439946207784;3946.7039972002217;3958.2677204716642;3958.4062390641056;3981.0843505993203;5482.424345443751;5508.228847961289;5537.36320323168;5553.389127196006;5558.77764889408;5805.593608447452;5863.208466857005;5868.2077919480325;5895.233619629506;5902.76388944446;5472.91790623141;5489.4236105209175;5515.117581186991;5518.806841760519;5532.363778165482;4970.453250142481;4990.451050384455;4998.875028124253;5005.5745261653165;5008.824691135793;5980.790672326535;6010.098485227251;6024.0662697282;6035.939640603626;6042.154098426224;5713.600047996601;5766.654000759968;5798.10129429939;5831.1544826000845;5835.912461312999;6019.6990150493175;6048.5161187105805;6052.515798736179;6057.42454466825;6071;5634.098544232903;5728.140778883034;5729.627574207663;5730.799422020209;5771.422857714195;5829.125631155361;5887.264091988502;5912.260967379078;5921.1414344918885;5974.731137098754;5717.456841600041;5721.5136713379825;5722.942770572241;5749.597528173155;5763.711814409179;5488.9725551371985;5556.333240011225;5576.414476480073;5627;5696.430356964271;5472.863178420572;5524.91712624319;5525.033119204043;5529.142982837667;5566.972165139148;5640.689762062962;5649.830505084854;5663.03728366187;5664.631798933056;5686.857828554319;5526.50261476469;5527.53015993645;5552.861178470571;5555.72221388937;5572.275604171594 15:25:43 INFO - PROCESS | 30025 | |1;ext.html;580.5413723158705;633.3191818794794;639.0446282807203;653.8855700252456;663.7577284291235;39273.607263927355;43059.78470107653;43070.569294307046;43123.35314970278;43148.568514314844;44706.32940505887;44798.328025079565;45586.544134558644;45605.31592026122;45751;8557.229849313577;8779.912200877974;8816.911830881672;8825.117488251155;8837;8728.301735861116;8828.779280517974;8834.86747698785;8843.646254149826;8851.159139881702;8839.24866386358;8854.716066170402;8861.822763544726;8915.732528024137;8920.018797932222;8787.560621968907;8848;8881.600327985223;8883.911160888372;8894.155055269739;8656.134386561356;8710.56447177645;8778.166074222938;8780.560971951441;8797.252233560155;41088.38367424491;41326.58673413288;41403.58596414027;41608.1678366431;41614.1677166455;2988.2188498402597;3380.121168496184;3655.5065066215984;3656.2870240302886;4241.384999175102;29040.854795726096;29468.8526557368;31584.842075789704;31782.682173178204;32269.83865080683;4078.469798926121;4210.51579068408;4276.9144617107795;4286.914261714748;4404.977975110104;346405;347420.9999999975;347886;349655;349823;60604;60778.39221607816;60962.695186523786;60983.39016609777;61391.693041534505;36062;36877.81561092231;64686.6765666178;65188;65193.674031630486;17268.913655431894;17333.56666083358;17435.302587896596;17452.127393630522;17529.29882804699;13216.008799340088;13307.401166947511;13312.933435332956;13337.066405351532;13339.066265361525;19506.561679790026;19541.316053938044;19628.509287267934;19652.803471965384;19676.21295148178;7310.488265821342;7435.814104647319;7526.623668816646;7684;7701.653425595861;956.9043095690514;1036.76154484469;1089.5478376473834;1128.920975531705;1155.4627098399117;31640.892568760442;31827.363452730817;31922.680773192435;32273.838630807164;32662.34675306528;1107.7064577886867;1110.7504057934823;1112.2881355932209;1196.1567095197865;1197.8742232065508;16909.661806763797;16953.83046169547;17056.2324695389;17060.317587296617;17186.742198866777;141351.29324353312;142267;142850;143223;143872.28063859613;54392.45607543953;55810.44189558052;55914;55986.44013559894;56024.719876400355;1383.1217177092417;1392.5752645443238;1408.7323408552343;1417.029334905593;1424.4515861393438;29998.70001299959;31142;31232;31388.215294616904;32202.67797321997;49493.752531237114;49588.25617615667;50144.24783628321;50173.24740128828;50200.748996254784;286865;286945;287088;288614;289047;27151.72848271492;27317.726822732315;27356.452870942867;27560.448791024468;27578.310542236606;47124.528754713385;47628.52371476241;47689.76155119202;54376;55159.72420137873;44354.55645443634;45691;45728.54271457243;46494.76752616351;47134;45456.772716136205;45542.31686524639;45764.771176143906;45843.3123503141;46663.76668116638;45171.774141129084;45189.32216016828;45702.314465283715;45778.54221457743;45985.540144599465;38948.2210355797;40845.79577102095;41013.38479922863;41175.38236926388;41351.7932410336;29688.55467167951;30364.24089397783;30400.543991840583;30589.847050764605;30596 15:25:43 INFO - PROCESS | 30025 | |2;jquery.html;388.39992212032405;401.1215438190363;404.997975010125;405.06228081990207;420.93896385024175;13888.23614701191;18059;18867.15097820598;19832.40502784917;19946.900265498687;18371.81628183718;18498.722519162224;18739;19253.422397328068;19390.90304548479;5421.430749771276;5588.329400471939;5795.565332600051;5799.3620701722775;5802.506786923117;5472.26124473195;5735.455131762496;5743.282089738783;5761.567882408815;5783.566232532556;5751.480233647669;5781.421857814206;5786.305643322808;5787.305523337205;5794.304683437972;6285.874282514347;6388.169537960059;6569.244536878273;6572.244191917943;6573.704183311763;5576.442355764431;5783.537317014629;5794.043982742843;5812.273465816773;5843.5033022193165;13408.329583520785;13409.932950335186;13592.456301747918;14867.776983345258;23581;2339.6490526421003;2372.7034120734907;2412.179858848;2441.584930561809;2518.9244322670347;9832.803343933081;10453.895461045367;10462.424566648831;10496.724454741545;10997.835032474519;1988.2245924089616;1999.1703443071108;2015.828654564364;2023.6559784836613;2050.2106688924723;65633.67183164053;72273;72619.63690181568;79328.20671793266;79598.20401795965;9023.954880225558;9529.285303602257;9616.134547890724;10082.420740636968;10646.467676616137;13084.934575327063;13296.667583310495;13396.397162127727;13513.932430337785;13533.39099740514;6469.5794773339585;6537.803865884031;7590.924090759132;8146.348292136615;8348.499090054616;8314.667413303521;8392.370572207108;8407.159284071666;8593.871091933626;8736.650533978696;4173.08192197716;4178.791060446965;4186.979065104716;4201.117765269267;4201.369794530845;1900.7053906644492;1929.9710504342447;1930.362980216525;2043.0499817584894;2079.199508189328;948.2982592881218;971.7522031881966;979.0552117206929;1010.570507534295;1085.0831047764784;17902.820971790377;19096.23615055382;19658.70511942322;20016.89991550033;20250.431564127648;1021;1024.5543188712784;1042.0673497219946;1053;1128.306091753582;10862.402567858686;11201.103911687045;11210.27133236337;11288.2662626929;11312.151588630886;448825;448939;450458;455075;469515;60403.395966039774;75889.62055189689;92906.53546732223;95465.5226723862;96200.51899740596;952.6665667016489;1003.2676146413013;1031.9484025798681;1098.19282827122;1098.566066403766;17693.38073167433;17729.822701772817;17988.820111798716;18053;18397.08014599238;42490.36264455974;42808.36128929076;42893.78553107214;43780.56219437765;43798.34302485529;59312;60628.78742425038;61696.38303617086;61734.69132654308;61948;8686.609102590272;8868.11318868108;8895.2883769297;8989.101089890959;9227.261819054325;12305.384730763248;13515.932420337835;13543;13685.726285474433;13724.794128087888;8514.48913065218;9439.772829532118;11712.180147389601;11833;11886.702832429259;11659.53361865516;11740.471678774305;11778.234414763014;11900.702482438008;11987.160898737005;11803.645890623295;12132.454039568063;12255.75488490243;12345.197562158432;12366.752664946831;8879.467231966102;8920.152585504367;8940.68707595231;9110.908890911007;9168.899732031932;11565.149576067788;11931.701707457383;12066.336351500751;12119.151659383759;12195.268283902995 15:25:43 INFO - PROCESS | 30025 | |3;mootools.html;1514.2277438506362;1554.8911576189669;1695.6354383807486;1825.0509734937825;1825.8995755233466;8094.5143291402555;8958.955205223972;8979.191872731453;9610;9643.180329671986;9328.48693321867;9375.859362109557;9389;9446.811063778738;9451.054894510546;2279.9886000569973;2320.3966968588206;2321.5008773113777;2328.6390609455475;2329.114936324203;2293.575688497625;2311.687922130516;2353.446939969106;2358.811295096388;2359;2290.5876942150408;2305.4697419593504;2306.4579823741406;2322.349742072214;2351.811855051592;2208.86746795192;2234.642457206839;2249.460129568908;2250.6849041134255;2263.3096905443917;2288;2306.1813056365086;2315.143396943142;2322.442613772699;2329.324495896205;9259.583318750672;9311.161995420445;9338.066193380675;9374.953125234399;9400.858987115198;5313.654612450178;5363.463653634644;5445.401005889349;5448.019356515825;5453.563714902798;3195.9201019974453;3284.146122008271;3337.916552086193;3462.740294477924;3500.6674365935196;1077.6659235636978;1098.9175811814146;1101.5703875488566;1110.3448965110542;1116.3357802107732;12887.420066097047;13004.869951300461;13017.739645207137;13028.80456793149;13031.478740850449;4526.728396296233;4608.631309495232;4669.369635099286;4704.9529504704515;4709.058188362341;10635.627753028606;10641.148708103334;10671.573137074585;10691.465426728633;10764.192685548458;5329.920051199235;5390.380106287827;5446.183072539151;5448.564114870801;5457.890842183134;4449.9555004450185;4946.3817022872145;5024.472430394829;5067.822626208065;5070.188769796815;4188.476440444944;4212.978935105305;4267.8292868284925;4283.571642835754;4324.329728892027;1315.4803852478217;1333.553259658006;1342.2282187742087;1352.3576301256824;1354.2551596621818;1006.2905651515664;1013.2400699475394;1014.5688082564881;1027.3938376357985;1036.6009086501751;6262.749490020348;6368.713407896656;6375.6174629522375;6423.614583124934;6538;426.1243145336302;429.51250330874683;429.7185343599947;430.8642777525132;441.3556207936449;4392.758398288125;4402.625776809007;4461.665375096881;4500.324951257272;4573.748443835554;11544.134189935789;11553.942230288794;11868.584599538975;11931.224470409396;11983.221090629082;11623.360715160747;11714.589989350332;11787.410629468492;11814.173007889538;11816.527338906519;8635.179657932613;8779.473231605996;8848.734537963743;8907.55462226899;8942.552872356357;6144.016957286813;6269.247690277196;6315.684215789284;6371.681415929278;6415.101885735908;10531.420771857622;10822;10885.564577416815;10932.945335273273;10974.945125274642;10838.13294936419;10880.401577913291;10977;11029.117670586495;11039.834402483808;7329.9267007329245;7979.16218797018;8116.513009219466;8188;8385.413021088465;9094.090590940854;9187.494687792236;9192.356535042483;9221.769455763659;9248.445093294155;7980.818121093097;7999.160088190656;8114.553699546345;8295.668173273001;8366.87449688243;8130.430869839054;8160.30637395828;8174.468659537111;8195.180481951995;8299.879516265228;7223.241559636373;7476.925230747841;7555.433342499445;7572.621368931642;7622.618869056414;5124.077666020152;5288.5769138469595;5399.973000134974;5427.457254274699;5440.782368705366;5073.518015788494;5132.563732082815;5246.501582349671;5270.051390749702;5271.262023316815 15:25:43 INFO - PROCESS | 30025 | |4;prototype.html;316.67698947073984;317.2956037596535;323.49211737572006;338.53113437888516;338.54296699455756;5416.295881535395;5417.7291135443165;5593.496585307326;5603.355614104379;5652.208690783294;5508.14623733321;5528.253685752432;5539.25220095287;5563.693996830173;5567.916481252784;4354.129174165164;4359.629431498326;4363.323684828841;4371.912561748763;4393.51671316155;4094.5086589609286;4303.978480107611;4315.892102697427;4380.875275491384;4383.517813040563;4260.339647354649;4264.957350426487;4287.592678695519;4292.549282325342;4304.375865499501;4285.378620100084;4293.184294983958;4293.506246781629;4314.676399270067;4353.5428779977965;3680.926381472382;3823.3691440912216;3834.69322454203;3840.615938406165;3880.20455806559;5283.682979021232;5308.23030660552;5319.7340132993195;5349.7057661828585;5420.972895135538;4978.452370239271;5016.372953380827;5019;5031.6729412588065;5034.622403319729;5579.972100139474;5796.97101514494;5822.883542329172;5872.794452194153;5874.853128671774;4186.727862688946;4195.286801243796;4201.138766552849;4207.242696314662;4219.556946520633;6060;6092.421219984094;6095.994160963436;6108.129179285773;6149.96925015372;4212.68404869636;4352.542982986804;4362.8909277267685;4375.343698445258;4382.846600368972;4399.5160532341415;4400.75795831226;4408.977955110268;4419.712718673276;4431.290993441034;4993.625478089158;5018.774155163026;5042.873928151752;5053.267276244924;5069.619778516626;5852.765889364463;5906.173135760997;5944.286685597669;5949.405059494103;5949.940500595025;1501.2643804535917;1507.811523559555;1521.8478152184693;1526.2673916520132;1526.5038862369775;959.467495539968;976.4483067067084;985.3841349156777;988.4316518002178;996.2627655534997;4972.502749725071;4992.850214493571;5043.3443652325695;5048.949510504922;5122.974385128051;5308.389535203501;5312.9810671944415;5362.490563396549;5383.515483606494;5500;4905.607551395879;5067.391912970394;5077.263796749525;5125.410577783528;5156.613254005964;2902.3746701847035;2929.282325830143;2930.6043684102374;2942.955855662167;2944.2050646325374;5628.943710562924;5653.519450846724;5661.395757951388;5664.272396918979;5681.34664513578;5191.351081114861;5482.342118945752;5514.503694667419;5554.750036248379;5598.132289495054;5629.802956896571;5648.971755141198;5649.237352957298;5654.85862853432;5687.345955215121;5114.974425127851;5428.728563571726;5434.2120392542065;5446.482584154499;5452;5376.381716102698;5393.892122157614;5397.78408863641;5411.810586629449;5431.293931789;5525.789684126382;5604.318704388748;5622.381538030895;5643.746031428514;6043.909341359795;4200.201961627281;4267.935980960226;4287.185434767384;4289.592488713567;4290.313549832012;5888.852778680396;5973.641581505123;6009.789657361971;6073;6084.148219249397;5683.687397193029;5703.205429339677;5886.94113058864;5892.908017653671;5908.881822363443;5748.770049197985;5761.2222349983895;5772.5381969441505;5825.912611310919;5831.504322132497;5692.6299790513485;5702.287214098238;5711.228984087261;5732.656040637575;5735.082386818089;5595.440455954372;5886.41135886425;5946.613470124601;5993.190919226023;5999.400059993965;5802.796902108406;5838.620489668158;5867.765289388377;5883.529317654492;5893.351731309638 15:25:43 INFO - PROCESS | 30025 | |5;yui.html;700.2402393403157;722.1478655186879;755.8450517643884;769.4767558060516;804.0994086622983;15173.16547589882;15669.608259793482;15732.426757324261;15779.447719329812;15854.128022958761;13968.231747253922;14022;14090.57728268151;14100.224487653175;14628;800.2397722163938;818.0960039156736;820.3888103363003;828.6271177969923;830.1573902488979;794.5272562825139;802.863513202754;806.5846089264004;807.5477732469807;807.7738233294687;851.642310229705;860.4321148042293;860.6127242740761;862.4135587800293;862.8144948836031;837.543538771368;846.4624963148415;849.6389034660275;849.8682704180862;850.3579797253096;623.7037407231529;624.5316012990257;627.3256249531726;627.5074066857541;629.3077614623922;14557.272136393138;14588.34352454142;14591.854081459156;14609.634759131106;14668.55994320172;13887.236202008886;13935.790963135561;13959.441622335095;13961.441542338294;14021.369038393195;11590;11604.941975290154;11622.476988535536;11796.174267801172;11863;3699.981500092509;3801.657850793442;3806;3821.5414150301863;3956.802159891994;58426.70786646082;59293.7035314825;59323;59455.702721486545;59745;10465.895341046644;11026.834597481044;11047.779044419067;11367.943160284145;11504.654860354203;16991;17009.91495042517;17103.315867365414;17209.397671081453;17227.913860430617;8569.657213711507;8620.181082797124;8717.302615790848;8774.473531588124;8837.469751814911;4483.618892394118;4572.761854431469;4662.696924699883;4905.23968784839;4907.288443175719;3309.172706823294;3396.983015084909;3397.5583174187195;3401.9659803402146;3404.8297585120645;598.099859711132;610.9266887973472;611.736953110158;627.2128478759128;632.4150161100963;749.8800191969259;769.5075151902787;770.5030255485293;774.3340726974798;775.7944144801633;9305.767355816024;9538.284628652878;9752.561134748954;9770.51147442625;9803.313768036312;694.4893563038786;707.4835370179798;720.9567425954459;721.801504586243;747.4132805747462;4556.84051058218;4683.625309975194;4765.618750499952;4770.594499467515;4774.474807771142;65556;67901;68654.31345686578;68728;68857;56337.71831140818;58547.70726146342;63899.361006390274;64040.35959640437;64364.35635643677;54842.725786370815;57205.42794572084;57692.711536442046;60891.39108608946;61187.69406152852;9858.457784821903;10035.598576056876;10144.898551014396;10186.134178594903;10208.489575521045;37654.62345376621;37837.43243851399;38676;38728.80635596804;38880;46633.30050049184;49480.75259623679;49688.50311496983;49909.25136123034;49935;12706.745865082461;12922.806157907451;12999.740005200032;13356.265405402795;13487.32563371808;14055.929720351332;14138.363773630006;14168.787468187778;14230.14619122856;14249.216293103978;13266.403011864297;13452.865471345161;13570.117942333716;13723.176609403468;14083.295835207993;13375.665608359479;13514.189148651112;13577.59267221985;13772.24252666113;13944.651383715489;12064.819027714766;13070.383205764216;13116.737665246832;13368.06423550381;13368.264745439094;11155.49800258974;11389.145814063813;11400.94299528497;11545.538178472767;11749.471273792871;11278;11407.885921140682;11438.94280528592;11577.536898523964;11655.59205427806 15:25:43 INFO - PROCESS | 30025 | __end_tp_report 15:25:43 INFO - PROCESS | 30025 | __start_cc_report 15:25:43 INFO - PROCESS | 30025 | _x_x_mozilla_cycle_collect,329 15:25:43 INFO - PROCESS | 30025 | __end_cc_report 15:25:43 INFO - PROCESS | 30025 | __startTimestamp1464819943895__endTimestamp 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | ------- Summary: start ------- 15:25:43 INFO - PROCESS | 30025 | Number of tests: 6 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | [#0] dojo.html Cycles:165 Average:5380.89 Median:5556.33 stddev:706.29 (12.7%) stddev-sans-first:686.51 15:25:43 INFO - PROCESS | 30025 | Values: 3154.3 3171.8 3184.4 3188.9 3192.4 5336.7 5453.5 5461.6 5490.9 5523.8 5392.3 5479.4 5482.2 5485.7 5570.4 4990.5 5037.9 5068.2 5121.5 5165.9 774.4 4880.3 4930.2 5053.0 5066.7 4902.9 4914.0 4934.9 4960.2 4981.3 4966.1 4967.9 4970.6 4981.7 4995.9 4913.7 4991.0 5029.6 5035.8 5041.1 5399.0 5435.5 5443.9 5450.1 5479.2 5636.8 5656.3 5917.1 5920.3 5948.5 5791.9 5835.2 5846.1 5876.2 5879.1 5450.9 5484.1 5484.4 5486.3 5515.3 5843.4 5846.8 5848.3 5849.4 5896.9 5706.6 5788.1 5791.3 5793.4 5800.7 5866.2 5870.0 5871.1 5880.5 5880.8 5509.7 5797.5 5821.6 5826.3 5836.7 5666.7 5681.2 5690.9 5711.0 5731.4 4368.1 4391.4 4396.2 4405.4 4429.7 3862.4 3946.7 3958.3 3958.4 3981.1 5482.4 5508.2 5537.4 5553.4 5558.8 5805.6 5863.2 5868.2 5895.2 5902.8 5472.9 5489.4 5515.1 5518.8 5532.4 4970.5 4990.5 4998.9 5005.6 5008.8 5980.8 6010.1 6024.1 6035.9 6042.2 5713.6 5766.7 5798.1 5831.2 5835.9 6019.7 6048.5 6052.5 6057.4 6071.0 5634.1 5728.1 5729.6 5730.8 5771.4 5829.1 5887.3 5912.3 5921.1 5974.7 5717.5 5721.5 5722.9 5749.6 5763.7 5489.0 5556.3 5576.4 5627.0 5696.4 5472.9 5524.9 5525.0 5529.1 5567.0 5640.7 5649.8 5663.0 5664.6 5686.9 5526.5 5527.5 5552.9 5555.7 5572.3 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | [#1] ext.html Cycles:180 Average:45527.40 Median:41611.17 stddev:71785.17 (172.5%) stddev-sans-first:71907.21 15:25:43 INFO - PROCESS | 30025 | Values: 580.5 633.3 639.0 653.9 663.8 39273.6 43059.8 43070.6 43123.4 43148.6 44706.3 44798.3 45586.5 45605.3 45751.0 8557.2 8779.9 8816.9 8825.1 8837.0 8728.3 8828.8 8834.9 8843.6 8851.2 8839.2 8854.7 8861.8 8915.7 8920.0 8787.6 8848.0 8881.6 8883.9 8894.2 8656.1 8710.6 8778.2 8780.6 8797.3 41088.4 41326.6 41403.6 41608.2 41614.2 2988.2 3380.1 3655.5 3656.3 4241.4 29040.9 29468.9 31584.8 31782.7 32269.8 4078.5 4210.5 4276.9 4286.9 4405.0 346405.0 347421.0 347886.0 349655.0 349823.0 60604.0 60778.4 60962.7 60983.4 61391.7 36062.0 36877.8 64686.7 65188.0 65193.7 17268.9 17333.6 17435.3 17452.1 17529.3 13216.0 13307.4 13312.9 13337.1 13339.1 19506.6 19541.3 19628.5 19652.8 19676.2 7310.5 7435.8 7526.6 7684.0 7701.7 956.9 1036.8 1089.5 1128.9 1155.5 31640.9 31827.4 31922.7 32273.8 32662.3 1107.7 1110.8 1112.3 1196.2 1197.9 16909.7 16953.8 17056.2 17060.3 17186.7 141351.3 142267.0 142850.0 143223.0 143872.3 54392.5 55810.4 55914.0 55986.4 56024.7 1383.1 1392.6 1408.7 1417.0 1424.5 29998.7 31142.0 31232.0 31388.2 32202.7 49493.8 49588.3 50144.2 50173.2 50200.7 286865.0 286945.0 287088.0 288614.0 289047.0 27151.7 27317.7 27356.5 27560.4 27578.3 47124.5 47628.5 47689.8 54376.0 55159.7 44354.6 45691.0 45728.5 46494.8 47134.0 45456.8 45542.3 45764.8 45843.3 46663.8 45171.8 45189.3 45702.3 45778.5 45985.5 38948.2 40845.8 41013.4 41175.4 41351.8 29688.6 30364.2 30400.5 30589.8 30596.0 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | [#2] jquery.html Cycles:180 Average:27473.61 Median:394.76 stddev:74978.61 (18993.4%) stddev-sans-first:75161.36 15:25:43 INFO - PROCESS | 30025 | Values: 388.4 401.1 405.0 405.1 420.9 13888.2 18059.0 18867.2 19832.4 19946.9 18371.8 18498.7 18739.0 19253.4 19390.9 5421.4 5588.3 5795.6 5799.4 5802.5 5472.3 5735.5 5743.3 5761.6 5783.6 5751.5 5781.4 5786.3 5787.3 5794.3 6285.9 6388.2 6569.2 6572.2 6573.7 5576.4 5783.5 5794.0 5812.3 5843.5 13408.3 13409.9 13592.5 14867.8 23581.0 2339.6 2372.7 2412.2 2441.6 2518.9 9832.8 10453.9 10462.4 10496.7 10997.8 1988.2 1999.2 2015.8 2023.7 2050.2 65633.7 72273.0 72619.6 79328.2 79598.2 9024.0 9529.3 9616.1 10082.4 10646.5 13084.9 13296.7 13396.4 13513.9 13533.4 6469.6 6537.8 7590.9 8146.3 8348.5 8314.7 8392.4 8407.2 8593.9 8736.7 4173.1 4178.8 4187.0 4201.1 4201.4 1900.7 1930.0 1930.4 2043.0 2079.2 948.3 971.8 979.1 1010.6 1085.1 17902.8 19096.2 19658.7 20016.9 20250.4 1021.0 1024.6 1042.1 1053.0 1128.3 10862.4 11201.1 11210.3 11288.3 11312.2 448825.0 448939.0 450458.0 455075.0 469515.0 60403.4 75889.6 92906.5 95465.5 96200.5 952.7 1003.3 1031.9 1098.2 1098.6 17693.4 17729.8 17988.8 18053.0 18397.1 42490.4 42808.4 42893.8 43780.6 43798.3 59312.0 60628.8 61696.4 61734.7 61948.0 8686.6 8868.1 8895.3 8989.1 9227.3 12305.4 13515.9 13543.0 13685.7 13724.8 8514.5 9439.8 11712.2 11833.0 11886.7 11659.5 11740.5 11778.2 11900.7 11987.2 11803.6 12132.5 12255.8 12345.2 12366.8 8879.5 8920.2 8940.7 9110.9 9168.9 11565.1 11931.7 12066.3 12119.2 12195.3 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | [#3] mootools.html Cycles:180 Average:6094.49 Median:2421.99 stddev:3573.89 (147.6%) stddev-sans-first:3567.34 15:25:43 INFO - PROCESS | 30025 | Values: 1514.2 1554.9 1695.6 1825.1 1825.9 8094.5 8959.0 8979.2 9610.0 9643.2 9328.5 9375.9 9389.0 9446.8 9451.1 2280.0 2320.4 2321.5 2328.6 2329.1 2293.6 2311.7 2353.4 2358.8 2359.0 2290.6 2305.5 2306.5 2322.3 2351.8 2208.9 2234.6 2249.5 2250.7 2263.3 2288.0 2306.2 2315.1 2322.4 2329.3 9259.6 9311.2 9338.1 9375.0 9400.9 5313.7 5363.5 5445.4 5448.0 5453.6 3195.9 3284.1 3337.9 3462.7 3500.7 1077.7 1098.9 1101.6 1110.3 1116.3 12887.4 13004.9 13017.7 13028.8 13031.5 4526.7 4608.6 4669.4 4705.0 4709.1 10635.6 10641.1 10671.6 10691.5 10764.2 5329.9 5390.4 5446.2 5448.6 5457.9 4450.0 4946.4 5024.5 5067.8 5070.2 4188.5 4213.0 4267.8 4283.6 4324.3 1315.5 1333.6 1342.2 1352.4 1354.3 1006.3 1013.2 1014.6 1027.4 1036.6 6262.7 6368.7 6375.6 6423.6 6538.0 426.1 429.5 429.7 430.9 441.4 4392.8 4402.6 4461.7 4500.3 4573.7 11544.1 11553.9 11868.6 11931.2 11983.2 11623.4 11714.6 11787.4 11814.2 11816.5 8635.2 8779.5 8848.7 8907.6 8942.6 6144.0 6269.2 6315.7 6371.7 6415.1 10531.4 10822.0 10885.6 10932.9 10974.9 10838.1 10880.4 10977.0 11029.1 11039.8 7329.9 7979.2 8116.5 8188.0 8385.4 9094.1 9187.5 9192.4 9221.8 9248.4 7980.8 7999.2 8114.6 8295.7 8366.9 8130.4 8160.3 8174.5 8195.2 8299.9 7223.2 7476.9 7555.4 7572.6 7622.6 5124.1 5288.6 5400.0 5427.5 5440.8 5073.5 5132.6 5246.5 5270.1 5271.3 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | [#4] prototype.html Cycles:180 Average:4780.66 Median:5388.70 stddev:1376.35 (25.5%) stddev-sans-first:1338.81 15:25:43 INFO - PROCESS | 30025 | Values: 316.7 317.3 323.5 338.5 338.5 5416.3 5417.7 5593.5 5603.4 5652.2 5508.1 5528.3 5539.3 5563.7 5567.9 4354.1 4359.6 4363.3 4371.9 4393.5 4094.5 4304.0 4315.9 4380.9 4383.5 4260.3 4265.0 4287.6 4292.5 4304.4 4285.4 4293.2 4293.5 4314.7 4353.5 3680.9 3823.4 3834.7 3840.6 3880.2 5283.7 5308.2 5319.7 5349.7 5421.0 4978.5 5016.4 5019.0 5031.7 5034.6 5580.0 5797.0 5822.9 5872.8 5874.9 4186.7 4195.3 4201.1 4207.2 4219.6 6060.0 6092.4 6096.0 6108.1 6150.0 4212.7 4352.5 4362.9 4375.3 4382.8 4399.5 4400.8 4409.0 4419.7 4431.3 4993.6 5018.8 5042.9 5053.3 5069.6 5852.8 5906.2 5944.3 5949.4 5949.9 1501.3 1507.8 1521.8 1526.3 1526.5 959.5 976.4 985.4 988.4 996.3 4972.5 4992.9 5043.3 5048.9 5123.0 5308.4 5313.0 5362.5 5383.5 5500.0 4905.6 5067.4 5077.3 5125.4 5156.6 2902.4 2929.3 2930.6 2943.0 2944.2 5628.9 5653.5 5661.4 5664.3 5681.3 5191.4 5482.3 5514.5 5554.8 5598.1 5629.8 5649.0 5649.2 5654.9 5687.3 5115.0 5428.7 5434.2 5446.5 5452.0 5376.4 5393.9 5397.8 5411.8 5431.3 5525.8 5604.3 5622.4 5643.7 6043.9 4200.2 4267.9 4287.2 4289.6 4290.3 5888.9 5973.6 6009.8 6073.0 6084.1 5683.7 5703.2 5886.9 5892.9 5908.9 5748.8 5761.2 5772.5 5825.9 5831.5 5692.6 5702.3 5711.2 5732.7 5735.1 5595.4 5886.4 5946.6 5993.2 5999.4 5802.8 5838.6 5867.8 5883.5 5893.4 15:25:43 INFO - PROCESS | 30025 | 15:25:43 INFO - PROCESS | 30025 | [#5] yui.html Cycles:180 Average:15972.41 Median:4564.80 stddev:19023.37 (416.7%) stddev-sans-first:19042.16 15:25:43 INFO - PROCESS | 30025 | Values: 700.2 722.1 755.8 769.5 804.1 15173.2 15669.6 15732.4 15779.4 15854.1 13968.2 14022.0 14090.6 14100.2 14628.0 800.2 818.1 820.4 828.6 830.2 794.5 802.9 806.6 807.5 807.8 851.6 860.4 860.6 862.4 862.8 837.5 846.5 849.6 849.9 850.4 623.7 624.5 627.3 627.5 629.3 14557.3 14588.3 14591.9 14609.6 14668.6 13887.2 13935.8 13959.4 13961.4 14021.4 11590.0 11604.9 11622.5 11796.2 11863.0 3700.0 3801.7 3806.0 3821.5 3956.8 58426.7 59293.7 59323.0 59455.7 59745.0 10465.9 11026.8 11047.8 11367.9 11504.7 16991.0 17009.9 17103.3 17209.4 17227.9 8569.7 8620.2 8717.3 8774.5 8837.5 4483.6 4572.8 4662.7 4905.2 4907.3 3309.2 3397.0 3397.6 3402.0 3404.8 598.1 610.9 611.7 627.2 632.4 749.9 769.5 770.5 774.3 775.8 9305.8 9538.3 9752.6 9770.5 9803.3 694.5 707.5 721.0 721.8 747.4 4556.8 4683.6 4765.6 4770.6 4774.5 65556.0 67901.0 68654.3 68728.0 68857.0 56337.7 58547.7 63899.4 64040.4 64364.4 54842.7 57205.4 57692.7 60891.4 61187.7 9858.5 10035.6 10144.9 10186.1 10208.5 37654.6 37837.4 38676.0 38728.8 38880.0 46633.3 49480.8 49688.5 49909.3 49935.0 12706.7 12922.8 12999.7 13356.3 13487.3 14055.9 14138.4 14168.8 14230.1 14249.2 13266.4 13452.9 13570.1 13723.2 14083.3 13375.7 13514.2 13577.6 13772.2 13944.7 12064.8 13070.4 13116.7 13368.1 13368.3 11155.5 11389.1 11400.9 11545.5 11749.5 11278.0 11407.9 11438.9 11577.5 11655.6 15:25:43 INFO - PROCESS | 30025 | -------- Summary: end -------- 15:25:43 INFO - PROCESS | 30025 | 15:25:44 INFO - TEST-INFO | 30025: exit 0 15:25:44 INFO - TEST-OK | dromaeo_css | took 1110088ms 15:25:44 INFO - TEST-START | kraken 15:25:44 INFO - operating with platform_type : linux_ 15:25:44 INFO - Initialising browser for kraken test... 15:25:44 INFO - TEST-INFO | started process 30266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEtniIL/profile http://localhost:56005/getInfo.html) 15:25:49 INFO - PROCESS | 30266 | __metrics Screen width/height:1600/1200 15:25:49 INFO - PROCESS | 30266 | colorDepth:24 15:25:49 INFO - PROCESS | 30266 | Browser inner width/height: 1024/697 15:25:49 INFO - PROCESS | 30266 | __metrics 15:25:49 ERROR - PROCESS | 30266 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 15:25:50 INFO - TEST-INFO | 30266: exit 0 15:25:50 INFO - Browser initialized. 15:25:50 INFO - Running cycle 1/1 for kraken test... 15:25:50 INFO - TEST-INFO | started process 30387 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEtniIL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:02 INFO - PROCESS | 30387 | RSS: Main: 167419904 15:26:02 INFO - PROCESS | 30387 | 15:26:04 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56005/tests/kraken/driver.html?testName=audio-beat-detection) 15:26:05 INFO - PROCESS | 30387 | RSS: Main: 173084672 15:26:05 INFO - PROCESS | 30387 | 15:26:08 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56005/tests/kraken/driver.html?testName=audio-dft) 15:26:09 INFO - PROCESS | 30387 | RSS: Main: 171982848 15:26:09 INFO - PROCESS | 30387 | 15:26:11 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56005/tests/kraken/driver.html?testName=audio-fft) 15:26:12 INFO - PROCESS | 30387 | RSS: Main: 167157760 15:26:12 INFO - PROCESS | 30387 | 15:26:14 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56005/tests/kraken/driver.html?testName=audio-oscillator) 15:26:15 INFO - PROCESS | 30387 | RSS: Main: 167460864 15:26:15 INFO - PROCESS | 30387 | 15:26:17 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:26:18 INFO - PROCESS | 30387 | RSS: Main: 167645184 15:26:18 INFO - PROCESS | 30387 | 15:26:21 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56005/tests/kraken/driver.html?testName=imaging-darkroom) 15:26:21 INFO - PROCESS | 30387 | RSS: Main: 167456768 15:26:21 INFO - PROCESS | 30387 | 15:26:25 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56005/tests/kraken/driver.html?testName=imaging-desaturate) 15:26:25 INFO - PROCESS | 30387 | RSS: Main: 165924864 15:26:25 INFO - PROCESS | 30387 | 15:26:28 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56005/tests/kraken/driver.html?testName=json-parse-financial) 15:26:29 INFO - PROCESS | 30387 | RSS: Main: 167874560 15:26:29 INFO - PROCESS | 30387 | 15:26:31 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:26:31 INFO - PROCESS | 30387 | RSS: Main: 168390656 15:26:31 INFO - PROCESS | 30387 | 15:26:33 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:26:34 INFO - PROCESS | 30387 | RSS: Main: 168701952 15:26:34 INFO - PROCESS | 30387 | 15:26:36 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:26:36 INFO - PROCESS | 30387 | RSS: Main: 169189376 15:26:36 INFO - PROCESS | 30387 | 15:26:39 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:26:40 INFO - PROCESS | 30387 | RSS: Main: 169029632 15:26:40 INFO - PROCESS | 30387 | 15:26:42 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:26:43 INFO - PROCESS | 30387 | RSS: Main: 168910848 15:26:43 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | Cycle 1(1): loaded http://localhost:56005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56005/tests/kraken/driver.html?testName=ai-astar) 15:26:45 INFO - PROCESS | 30387 | RSS: Main: 169664512 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | __start_tp_report 15:26:45 INFO - PROCESS | 30387 | _x_x_mozilla_page_load 15:26:45 INFO - PROCESS | 30387 | _x_x_mozilla_page_load_details 15:26:45 INFO - PROCESS | 30387 | |i|pagename|runs| 15:26:45 INFO - PROCESS | 30387 | |0;ai-astar;113;105;110;110;112;112;111;112;112;111 15:26:45 INFO - PROCESS | 30387 | |1;audio-beat-detection;158;131;162;179;130;156;158;136;195;125 15:26:45 INFO - PROCESS | 30387 | |2;audio-dft;162;162;163;164;165;164;177;162;161;164 15:26:45 INFO - PROCESS | 30387 | |3;audio-fft;74;76;75;166;74;75;76;74;166;75 15:26:45 INFO - PROCESS | 30387 | |4;audio-oscillator;84;83;155;85;83;85;134;84;85;144 15:26:45 INFO - PROCESS | 30387 | |5;imaging-gaussian-blur;117;117;117;119;118;118;117;117;118;118 15:26:45 INFO - PROCESS | 30387 | |6;imaging-darkroom;147;147;146;147;148;147;147;147;148;148 15:26:45 INFO - PROCESS | 30387 | |7;imaging-desaturate;96;96;96;95;96;95;96;96;96;96 15:26:45 INFO - PROCESS | 30387 | |8;json-parse-financial;53;55;54;53;55;53;53;58;53;53 15:26:45 INFO - PROCESS | 30387 | |9;json-stringify-tinderbox;63;63;63;63;63;63;63;63;63;63 15:26:45 INFO - PROCESS | 30387 | |10;stanford-crypto-aes;82;80;80;81;80;86;83;81;117;88 15:26:45 INFO - PROCESS | 30387 | |11;stanford-crypto-ccm;134;140;120;132;148;130;126;131;134;121 15:26:45 INFO - PROCESS | 30387 | |12;stanford-crypto-pbkdf2;165;167;165;169;175;163;170;163;166;165 15:26:45 INFO - PROCESS | 30387 | |13;stanford-crypto-sha256-iterative;62;65;64;63;63;62;62;62;62;68 15:26:45 INFO - PROCESS | 30387 | __end_tp_report 15:26:45 INFO - PROCESS | 30387 | __start_cc_report 15:26:45 INFO - PROCESS | 30387 | _x_x_mozilla_cycle_collect,892 15:26:45 INFO - PROCESS | 30387 | __end_cc_report 15:26:45 INFO - PROCESS | 30387 | __startTimestamp1464820005498__endTimestamp 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | ------- Summary: start ------- 15:26:45 INFO - PROCESS | 30387 | Number of tests: 14 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:2.25 (2.0%) stddev-sans-first:2.24 15:26:45 INFO - PROCESS | 30387 | Values: 113.0 105.0 110.0 110.0 112.0 112.0 111.0 112.0 112.0 111.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#1] audio-beat-detection Cycles:10 Average:153.00 Median:158.00 stddev:22.77 (14.4%) stddev-sans-first:24.08 15:26:45 INFO - PROCESS | 30387 | Values: 158.0 131.0 162.0 179.0 130.0 156.0 158.0 136.0 195.0 125.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:4.60 (2.8%) stddev-sans-first:4.80 15:26:45 INFO - PROCESS | 30387 | Values: 162.0 162.0 163.0 164.0 165.0 164.0 177.0 162.0 161.0 164.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#3] audio-fft Cycles:10 Average:93.10 Median:75.00 stddev:38.43 (51.2%) stddev-sans-first:40.13 15:26:45 INFO - PROCESS | 30387 | Values: 74.0 76.0 75.0 166.0 74.0 75.0 76.0 74.0 166.0 75.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:84.00 stddev:29.50 (35.1%) stddev-sans-first:30.55 15:26:45 INFO - PROCESS | 30387 | Values: 84.0 83.0 155.0 85.0 83.0 85.0 134.0 84.0 85.0 144.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 15:26:45 INFO - PROCESS | 30387 | Values: 117.0 117.0 117.0 119.0 118.0 118.0 117.0 117.0 118.0 118.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 15:26:45 INFO - PROCESS | 30387 | Values: 147.0 147.0 146.0 147.0 148.0 147.0 147.0 147.0 148.0 148.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:26:45 INFO - PROCESS | 30387 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:53.50 stddev:1.63 (3.1%) stddev-sans-first:1.69 15:26:45 INFO - PROCESS | 30387 | Values: 53.0 55.0 54.0 53.0 55.0 53.0 53.0 58.0 53.0 53.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 15:26:45 INFO - PROCESS | 30387 | Values: 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:81.50 stddev:11.29 (13.9%) stddev-sans-first:11.89 15:26:45 INFO - PROCESS | 30387 | Values: 82.0 80.0 80.0 81.0 80.0 86.0 83.0 81.0 117.0 88.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#11] stanford-crypto-ccm Cycles:10 Average:131.60 Median:133.00 stddev:8.38 (6.3%) stddev-sans-first:8.85 15:26:45 INFO - PROCESS | 30387 | Values: 134.0 140.0 120.0 132.0 148.0 130.0 126.0 131.0 134.0 121.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:166.50 stddev:3.68 (2.2%) stddev-sans-first:3.84 15:26:45 INFO - PROCESS | 30387 | Values: 165.0 167.0 165.0 169.0 175.0 163.0 170.0 163.0 166.0 165.0 15:26:45 INFO - PROCESS | 30387 | 15:26:45 INFO - PROCESS | 30387 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.95 (3.1%) stddev-sans-first:2.01 15:26:45 INFO - PROCESS | 30387 | Values: 62.0 65.0 64.0 63.0 63.0 62.0 62.0 62.0 62.0 68.0 15:26:45 INFO - PROCESS | 30387 | -------- Summary: end -------- 15:26:45 INFO - PROCESS | 30387 | 15:26:46 INFO - TEST-INFO | 30387: exit 0 15:26:46 INFO - TEST-OK | kraken | took 61809ms 15:26:46 INFO - SUITE-END | took 1171s 15:26:46 INFO - Completed test suite (00:19:32) 15:26:46 INFO - javascript benchmark 15:26:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7174.590102893928, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3154.3375891062874, 3171.8096914185166, 3184.4108839864625, 3188.9362212755746, 3192.393445245403, 5336.6531175473565, 5453.454654534545, 5461.563074954004, 5490.862728431781, 5523.751431185597, 5392.299001129848, 5479.424660410659, 5482.177673349, 5485.670859748418, 5570.387257401682, 4990.47600002, 5037.899242015157, 5068.1890897456415, 5121.462246464123, 5165.9225111623355, 774.4271128512673, 4880.292357608145, 4930.235813448921, 5053.014662140884, 5066.721330326849, 4902.926456103161, 4914.04176185644, 4934.925976110343, 4960.23116416956, 4981.252812078199, 4966.081274964144, 4967.900641987176, 4970.627202959792, 4981.651284410093, 4995.875103122415, 4913.680610760289, 4991.0, 5029.572486338666, 5035.798568057273, 5041.067402530543, 5398.973005134949, 5435.483629055233, 5443.945560544423, 5450.100733378988, 5479.205515200274, 5636.802711905105, 5656.264685590888, 5917.082852157922, 5920.2895652521975, 5948.524118070544, 5791.913121303184, 5835.241418615596, 5846.093855452412, 5876.177335173079, 5879.147523609053, 5450.945490545084, 5484.149956756709, 5484.424135465759, 5486.286782718261, 5515.283013208297, 5843.357230704619, 5846.824595262149, 5848.2689663792025, 5849.3858144894175, 5896.941030589725, 5706.572007099484, 5788.0739081746715, 5791.276090488689, 5793.362730099686, 5800.6809625471005, 5866.178734977107, 5869.970650146722, 5871.1486834409625, 5880.529557635379, 5880.794172203952, 5509.724513774296, 5797.507211886952, 5821.621594596338, 5826.329972053183, 5836.737346819318, 5666.744996475169, 5681.2330335404195, 5690.9146362804595, 5711.029125048752, 5731.398203188689, 4368.060866913629, 4391.363252328394, 4396.230659634615, 4405.3612226227015, 4429.689921705514, 3862.439946207784, 3946.7039972002217, 3958.2677204716642, 3958.4062390641056, 3981.0843505993203, 5482.424345443751, 5508.228847961289, 5537.36320323168, 5553.389127196006, 5558.77764889408, 5805.593608447452, 5863.208466857005, 5868.2077919480325, 5895.233619629506, 5902.76388944446, 5472.91790623141, 5489.4236105209175, 5515.117581186991, 5518.806841760519, 5532.363778165482, 4970.453250142481, 4990.451050384455, 4998.875028124253, 5005.5745261653165, 5008.824691135793, 5980.790672326535, 6010.098485227251, 6024.0662697282, 6035.939640603626, 6042.154098426224, 5713.600047996601, 5766.654000759968, 5798.10129429939, 5831.1544826000845, 5835.912461312999, 6019.6990150493175, 6048.5161187105805, 6052.515798736179, 6057.42454466825, 6071.0, 5634.098544232903, 5728.140778883034, 5729.627574207663, 5730.799422020209, 5771.422857714195, 5829.125631155361, 5887.264091988502, 5912.260967379078, 5921.1414344918885, 5974.731137098754, 5717.456841600041, 5721.5136713379825, 5722.942770572241, 5749.597528173155, 5763.711814409179, 5488.9725551371985, 5556.333240011225, 5576.414476480073, 5627.0, 5696.430356964271, 5472.863178420572, 5524.91712624319, 5525.033119204043, 5529.142982837667, 5566.972165139148, 5640.689762062962, 5649.830505084854, 5663.03728366187, 5664.631798933056, 5686.857828554319, 5526.50261476469, 5527.53015993645, 5552.861178470571, 5555.72221388937, 5572.275604171594], "value": 5335.679844412534, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.5413723158705, 633.3191818794794, 639.0446282807203, 653.8855700252456, 663.7577284291235, 39273.607263927355, 43059.78470107653, 43070.569294307046, 43123.35314970278, 43148.568514314844, 44706.32940505887, 44798.328025079565, 45586.544134558644, 45605.31592026122, 45751.0, 8557.229849313577, 8779.912200877974, 8816.911830881672, 8825.117488251155, 8837.0, 8728.301735861116, 8828.779280517974, 8834.86747698785, 8843.646254149826, 8851.159139881702, 8839.24866386358, 8854.716066170402, 8861.822763544726, 8915.732528024137, 8920.018797932222, 8787.560621968907, 8848.0, 8881.600327985223, 8883.911160888372, 8894.155055269739, 8656.134386561356, 8710.56447177645, 8778.166074222938, 8780.560971951441, 8797.252233560155, 41088.38367424491, 41326.58673413288, 41403.58596414027, 41608.1678366431, 41614.1677166455, 2988.2188498402597, 3380.121168496184, 3655.5065066215984, 3656.2870240302886, 4241.384999175102, 29040.854795726096, 29468.8526557368, 31584.842075789704, 31782.682173178204, 32269.83865080683, 4078.469798926121, 4210.51579068408, 4276.9144617107795, 4286.914261714748, 4404.977975110104, 346405.0, 347420.9999999975, 347886.0, 349655.0, 349823.0, 60604.0, 60778.39221607816, 60962.695186523786, 60983.39016609777, 61391.693041534505, 36062.0, 36877.81561092231, 64686.6765666178, 65188.0, 65193.674031630486, 17268.913655431894, 17333.56666083358, 17435.302587896596, 17452.127393630522, 17529.29882804699, 13216.008799340088, 13307.401166947511, 13312.933435332956, 13337.066405351532, 13339.066265361525, 19506.561679790026, 19541.316053938044, 19628.509287267934, 19652.803471965384, 19676.21295148178, 7310.488265821342, 7435.814104647319, 7526.623668816646, 7684.0, 7701.653425595861, 956.9043095690514, 1036.76154484469, 1089.5478376473834, 1128.920975531705, 1155.4627098399117, 31640.892568760442, 31827.363452730817, 31922.680773192435, 32273.838630807164, 32662.34675306528, 1107.7064577886867, 1110.7504057934823, 1112.2881355932209, 1196.1567095197865, 1197.8742232065508, 16909.661806763797, 16953.83046169547, 17056.2324695389, 17060.317587296617, 17186.742198866777, 141351.29324353312, 142267.0, 142850.0, 143223.0, 143872.28063859613, 54392.45607543953, 55810.44189558052, 55914.0, 55986.44013559894, 56024.719876400355, 1383.1217177092417, 1392.5752645443238, 1408.7323408552343, 1417.029334905593, 1424.4515861393438, 29998.70001299959, 31142.0, 31232.0, 31388.215294616904, 32202.67797321997, 49493.752531237114, 49588.25617615667, 50144.24783628321, 50173.24740128828, 50200.748996254784, 286865.0, 286945.0, 287088.0, 288614.0, 289047.0, 27151.72848271492, 27317.726822732315, 27356.452870942867, 27560.448791024468, 27578.310542236606, 47124.528754713385, 47628.52371476241, 47689.76155119202, 54376.0, 55159.72420137873, 44354.55645443634, 45691.0, 45728.54271457243, 46494.76752616351, 47134.0, 45456.772716136205, 45542.31686524639, 45764.771176143906, 45843.3123503141, 46663.76668116638, 45171.774141129084, 45189.32216016828, 45702.314465283715, 45778.54221457743, 45985.540144599465, 38948.2210355797, 40845.79577102095, 41013.38479922863, 41175.38236926388, 41351.7932410336, 29688.55467167951, 30364.24089397783, 30400.543991840583, 30589.847050764605, 30596.0], "value": 19485.114259863058, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.39992212032405, 401.1215438190363, 404.997975010125, 405.06228081990207, 420.93896385024175, 13888.23614701191, 18059.0, 18867.15097820598, 19832.40502784917, 19946.900265498687, 18371.81628183718, 18498.722519162224, 18739.0, 19253.422397328068, 19390.90304548479, 5421.430749771276, 5588.329400471939, 5795.565332600051, 5799.3620701722775, 5802.506786923117, 5472.26124473195, 5735.455131762496, 5743.282089738783, 5761.567882408815, 5783.566232532556, 5751.480233647669, 5781.421857814206, 5786.305643322808, 5787.305523337205, 5794.304683437972, 6285.874282514347, 6388.169537960059, 6569.244536878273, 6572.244191917943, 6573.704183311763, 5576.442355764431, 5783.537317014629, 5794.043982742843, 5812.273465816773, 5843.5033022193165, 13408.329583520785, 13409.932950335186, 13592.456301747918, 14867.776983345258, 23581.0, 2339.6490526421003, 2372.7034120734907, 2412.179858848, 2441.584930561809, 2518.9244322670347, 9832.803343933081, 10453.895461045367, 10462.424566648831, 10496.724454741545, 10997.835032474519, 1988.2245924089616, 1999.1703443071108, 2015.828654564364, 2023.6559784836613, 2050.2106688924723, 65633.67183164053, 72273.0, 72619.63690181568, 79328.20671793266, 79598.20401795965, 9023.954880225558, 9529.285303602257, 9616.134547890724, 10082.420740636968, 10646.467676616137, 13084.934575327063, 13296.667583310495, 13396.397162127727, 13513.932430337785, 13533.39099740514, 6469.5794773339585, 6537.803865884031, 7590.924090759132, 8146.348292136615, 8348.499090054616, 8314.667413303521, 8392.370572207108, 8407.159284071666, 8593.871091933626, 8736.650533978696, 4173.08192197716, 4178.791060446965, 4186.979065104716, 4201.117765269267, 4201.369794530845, 1900.7053906644492, 1929.9710504342447, 1930.362980216525, 2043.0499817584894, 2079.199508189328, 948.2982592881218, 971.7522031881966, 979.0552117206929, 1010.570507534295, 1085.0831047764784, 17902.820971790377, 19096.23615055382, 19658.70511942322, 20016.89991550033, 20250.431564127648, 1021.0, 1024.5543188712784, 1042.0673497219946, 1053.0, 1128.306091753582, 10862.402567858686, 11201.103911687045, 11210.27133236337, 11288.2662626929, 11312.151588630886, 448825.0, 448939.0, 450458.0, 455075.0, 469515.0, 60403.395966039774, 75889.62055189689, 92906.53546732223, 95465.5226723862, 96200.51899740596, 952.6665667016489, 1003.2676146413013, 1031.9484025798681, 1098.19282827122, 1098.566066403766, 17693.38073167433, 17729.822701772817, 17988.820111798716, 18053.0, 18397.08014599238, 42490.36264455974, 42808.36128929076, 42893.78553107214, 43780.56219437765, 43798.34302485529, 59312.0, 60628.78742425038, 61696.38303617086, 61734.69132654308, 61948.0, 8686.609102590272, 8868.11318868108, 8895.2883769297, 8989.101089890959, 9227.261819054325, 12305.384730763248, 13515.932420337835, 13543.0, 13685.726285474433, 13724.794128087888, 8514.48913065218, 9439.772829532118, 11712.180147389601, 11833.0, 11886.702832429259, 11659.53361865516, 11740.471678774305, 11778.234414763014, 11900.702482438008, 11987.160898737005, 11803.645890623295, 12132.454039568063, 12255.75488490243, 12345.197562158432, 12366.752664946831, 8879.467231966102, 8920.152585504367, 8940.68707595231, 9110.908890911007, 9168.899732031932, 11565.149576067788, 11931.701707457383, 12066.336351500751, 12119.151659383759, 12195.268283902995], "value": 9097.392436189586, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.2277438506362, 1554.8911576189669, 1695.6354383807486, 1825.0509734937825, 1825.8995755233466, 8094.5143291402555, 8958.955205223972, 8979.191872731453, 9610.0, 9643.180329671986, 9328.48693321867, 9375.859362109557, 9389.0, 9446.811063778738, 9451.054894510546, 2279.9886000569973, 2320.3966968588206, 2321.5008773113777, 2328.6390609455475, 2329.114936324203, 2293.575688497625, 2311.687922130516, 2353.446939969106, 2358.811295096388, 2359.0, 2290.5876942150408, 2305.4697419593504, 2306.4579823741406, 2322.349742072214, 2351.811855051592, 2208.86746795192, 2234.642457206839, 2249.460129568908, 2250.6849041134255, 2263.3096905443917, 2288.0, 2306.1813056365086, 2315.143396943142, 2322.442613772699, 2329.324495896205, 9259.583318750672, 9311.161995420445, 9338.066193380675, 9374.953125234399, 9400.858987115198, 5313.654612450178, 5363.463653634644, 5445.401005889349, 5448.019356515825, 5453.563714902798, 3195.9201019974453, 3284.146122008271, 3337.916552086193, 3462.740294477924, 3500.6674365935196, 1077.6659235636978, 1098.9175811814146, 1101.5703875488566, 1110.3448965110542, 1116.3357802107732, 12887.420066097047, 13004.869951300461, 13017.739645207137, 13028.80456793149, 13031.478740850449, 4526.728396296233, 4608.631309495232, 4669.369635099286, 4704.9529504704515, 4709.058188362341, 10635.627753028606, 10641.148708103334, 10671.573137074585, 10691.465426728633, 10764.192685548458, 5329.920051199235, 5390.380106287827, 5446.183072539151, 5448.564114870801, 5457.890842183134, 4449.9555004450185, 4946.3817022872145, 5024.472430394829, 5067.822626208065, 5070.188769796815, 4188.476440444944, 4212.978935105305, 4267.8292868284925, 4283.571642835754, 4324.329728892027, 1315.4803852478217, 1333.553259658006, 1342.2282187742087, 1352.3576301256824, 1354.2551596621818, 1006.2905651515664, 1013.2400699475394, 1014.5688082564881, 1027.3938376357985, 1036.6009086501751, 6262.749490020348, 6368.713407896656, 6375.6174629522375, 6423.614583124934, 6538.0, 426.1243145336302, 429.51250330874683, 429.7185343599947, 430.8642777525132, 441.3556207936449, 4392.758398288125, 4402.625776809007, 4461.665375096881, 4500.324951257272, 4573.748443835554, 11544.134189935789, 11553.942230288794, 11868.584599538975, 11931.224470409396, 11983.221090629082, 11623.360715160747, 11714.589989350332, 11787.410629468492, 11814.173007889538, 11816.527338906519, 8635.179657932613, 8779.473231605996, 8848.734537963743, 8907.55462226899, 8942.552872356357, 6144.016957286813, 6269.247690277196, 6315.684215789284, 6371.681415929278, 6415.101885735908, 10531.420771857622, 10822.0, 10885.564577416815, 10932.945335273273, 10974.945125274642, 10838.13294936419, 10880.401577913291, 10977.0, 11029.117670586495, 11039.834402483808, 7329.9267007329245, 7979.16218797018, 8116.513009219466, 8188.0, 8385.413021088465, 9094.090590940854, 9187.494687792236, 9192.356535042483, 9221.769455763659, 9248.445093294155, 7980.818121093097, 7999.160088190656, 8114.553699546345, 8295.668173273001, 8366.87449688243, 8130.430869839054, 8160.30637395828, 8174.468659537111, 8195.180481951995, 8299.879516265228, 7223.241559636373, 7476.925230747841, 7555.433342499445, 7572.621368931642, 7622.618869056414, 5124.077666020152, 5288.5769138469595, 5399.973000134974, 5427.457254274699, 5440.782368705366, 5073.518015788494, 5132.563732082815, 5246.501582349671, 5270.051390749702, 5271.262023316815], "value": 4731.130340811398, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.67698947073984, 317.2956037596535, 323.49211737572006, 338.53113437888516, 338.54296699455756, 5416.295881535395, 5417.7291135443165, 5593.496585307326, 5603.355614104379, 5652.208690783294, 5508.14623733321, 5528.253685752432, 5539.25220095287, 5563.693996830173, 5567.916481252784, 4354.129174165164, 4359.629431498326, 4363.323684828841, 4371.912561748763, 4393.51671316155, 4094.5086589609286, 4303.978480107611, 4315.892102697427, 4380.875275491384, 4383.517813040563, 4260.339647354649, 4264.957350426487, 4287.592678695519, 4292.549282325342, 4304.375865499501, 4285.378620100084, 4293.184294983958, 4293.506246781629, 4314.676399270067, 4353.5428779977965, 3680.926381472382, 3823.3691440912216, 3834.69322454203, 3840.615938406165, 3880.20455806559, 5283.682979021232, 5308.23030660552, 5319.7340132993195, 5349.7057661828585, 5420.972895135538, 4978.452370239271, 5016.372953380827, 5019.0, 5031.6729412588065, 5034.622403319729, 5579.972100139474, 5796.97101514494, 5822.883542329172, 5872.794452194153, 5874.853128671774, 4186.727862688946, 4195.286801243796, 4201.138766552849, 4207.242696314662, 4219.556946520633, 6060.0, 6092.421219984094, 6095.994160963436, 6108.129179285773, 6149.96925015372, 4212.68404869636, 4352.542982986804, 4362.8909277267685, 4375.343698445258, 4382.846600368972, 4399.5160532341415, 4400.75795831226, 4408.977955110268, 4419.712718673276, 4431.290993441034, 4993.625478089158, 5018.774155163026, 5042.873928151752, 5053.267276244924, 5069.619778516626, 5852.765889364463, 5906.173135760997, 5944.286685597669, 5949.405059494103, 5949.940500595025, 1501.2643804535917, 1507.811523559555, 1521.8478152184693, 1526.2673916520132, 1526.5038862369775, 959.467495539968, 976.4483067067084, 985.3841349156777, 988.4316518002178, 996.2627655534997, 4972.502749725071, 4992.850214493571, 5043.3443652325695, 5048.949510504922, 5122.974385128051, 5308.389535203501, 5312.9810671944415, 5362.490563396549, 5383.515483606494, 5500.0, 4905.607551395879, 5067.391912970394, 5077.263796749525, 5125.410577783528, 5156.613254005964, 2902.3746701847035, 2929.282325830143, 2930.6043684102374, 2942.955855662167, 2944.2050646325374, 5628.943710562924, 5653.519450846724, 5661.395757951388, 5664.272396918979, 5681.34664513578, 5191.351081114861, 5482.342118945752, 5514.503694667419, 5554.750036248379, 5598.132289495054, 5629.802956896571, 5648.971755141198, 5649.237352957298, 5654.85862853432, 5687.345955215121, 5114.974425127851, 5428.728563571726, 5434.2120392542065, 5446.482584154499, 5452.0, 5376.381716102698, 5393.892122157614, 5397.78408863641, 5411.810586629449, 5431.293931789, 5525.789684126382, 5604.318704388748, 5622.381538030895, 5643.746031428514, 6043.909341359795, 4200.201961627281, 4267.935980960226, 4287.185434767384, 4289.592488713567, 4290.313549832012, 5888.852778680396, 5973.641581505123, 6009.789657361971, 6073.0, 6084.148219249397, 5683.687397193029, 5703.205429339677, 5886.94113058864, 5892.908017653671, 5908.881822363443, 5748.770049197985, 5761.2222349983895, 5772.5381969441505, 5825.912611310919, 5831.504322132497, 5692.6299790513485, 5702.287214098238, 5711.228984087261, 5732.656040637575, 5735.082386818089, 5595.440455954372, 5886.41135886425, 5946.613470124601, 5993.190919226023, 5999.400059993965, 5802.796902108406, 5838.620489668158, 5867.765289388377, 5883.529317654492, 5893.351731309638], "value": 4338.992391470901, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.2402393403157, 722.1478655186879, 755.8450517643884, 769.4767558060516, 804.0994086622983, 15173.16547589882, 15669.608259793482, 15732.426757324261, 15779.447719329812, 15854.128022958761, 13968.231747253922, 14022.0, 14090.57728268151, 14100.224487653175, 14628.0, 800.2397722163938, 818.0960039156736, 820.3888103363003, 828.6271177969923, 830.1573902488979, 794.5272562825139, 802.863513202754, 806.5846089264004, 807.5477732469807, 807.7738233294687, 851.642310229705, 860.4321148042293, 860.6127242740761, 862.4135587800293, 862.8144948836031, 837.543538771368, 846.4624963148415, 849.6389034660275, 849.8682704180862, 850.3579797253096, 623.7037407231529, 624.5316012990257, 627.3256249531726, 627.5074066857541, 629.3077614623922, 14557.272136393138, 14588.34352454142, 14591.854081459156, 14609.634759131106, 14668.55994320172, 13887.236202008886, 13935.790963135561, 13959.441622335095, 13961.441542338294, 14021.369038393195, 11590.0, 11604.941975290154, 11622.476988535536, 11796.174267801172, 11863.0, 3699.981500092509, 3801.657850793442, 3806.0, 3821.5414150301863, 3956.802159891994, 58426.70786646082, 59293.7035314825, 59323.0, 59455.702721486545, 59745.0, 10465.895341046644, 11026.834597481044, 11047.779044419067, 11367.943160284145, 11504.654860354203, 16991.0, 17009.91495042517, 17103.315867365414, 17209.397671081453, 17227.913860430617, 8569.657213711507, 8620.181082797124, 8717.302615790848, 8774.473531588124, 8837.469751814911, 4483.618892394118, 4572.761854431469, 4662.696924699883, 4905.23968784839, 4907.288443175719, 3309.172706823294, 3396.983015084909, 3397.5583174187195, 3401.9659803402146, 3404.8297585120645, 598.099859711132, 610.9266887973472, 611.736953110158, 627.2128478759128, 632.4150161100963, 749.8800191969259, 769.5075151902787, 770.5030255485293, 774.3340726974798, 775.7944144801633, 9305.767355816024, 9538.284628652878, 9752.561134748954, 9770.51147442625, 9803.313768036312, 694.4893563038786, 707.4835370179798, 720.9567425954459, 721.801504586243, 747.4132805747462, 4556.84051058218, 4683.625309975194, 4765.618750499952, 4770.594499467515, 4774.474807771142, 65556.0, 67901.0, 68654.31345686578, 68728.0, 68857.0, 56337.71831140818, 58547.70726146342, 63899.361006390274, 64040.35959640437, 64364.35635643677, 54842.725786370815, 57205.42794572084, 57692.711536442046, 60891.39108608946, 61187.69406152852, 9858.457784821903, 10035.598576056876, 10144.898551014396, 10186.134178594903, 10208.489575521045, 37654.62345376621, 37837.43243851399, 38676.0, 38728.80635596804, 38880.0, 46633.30050049184, 49480.75259623679, 49688.50311496983, 49909.25136123034, 49935.0, 12706.745865082461, 12922.806157907451, 12999.740005200032, 13356.265405402795, 13487.32563371808, 14055.929720351332, 14138.363773630006, 14168.787468187778, 14230.14619122856, 14249.216293103978, 13266.403011864297, 13452.865471345161, 13570.117942333716, 13723.176609403468, 14083.295835207993, 13375.665608359479, 13514.189148651112, 13577.59267221985, 13772.24252666113, 13944.651383715489, 12064.819027714766, 13070.383205764216, 13116.737665246832, 13368.06423550381, 13368.264745439094, 11155.49800258974, 11389.145814063813, 11400.94299528497, 11545.538178472767, 11749.471273792871, 11278.0, 11407.885921140682, 11438.94280528592, 11577.536898523964, 11655.59205427806], "value": 7023.88157401296, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 110.0, 110.0, 112.0, 112.0, 111.0, 112.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 131.0, 162.0, 179.0, 130.0, 156.0, 158.0, 136.0, 195.0, 125.0], "value": 153.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 164.0, 165.0, 164.0, 177.0, 162.0, 161.0, 164.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 166.0, 74.0, 75.0, 76.0, 74.0, 166.0, 75.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 155.0, 85.0, 83.0, 85.0, 134.0, 84.0, 85.0, 144.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 119.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 54.0, 53.0, 55.0, 53.0, 53.0, 58.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 80.0, 81.0, 80.0, 86.0, 83.0, 81.0, 117.0, 88.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 140.0, 120.0, 132.0, 148.0, 130.0, 126.0, 131.0, 134.0, 121.0], "value": 131.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 165.0, 169.0, 175.0, 163.0, 170.0, 163.0, 166.0, 165.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 64.0, 63.0, 63.0, 62.0, 62.0, 62.0, 62.0, 68.0], "value": 63.3, "unit": "score"}]}]} 15:26:46 INFO - Return code: 0 15:26:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:26:46 ERROR - # TBPL SUCCESS # 15:26:46 INFO - Running post-action listener: _resource_record_post_action 15:26:46 INFO - ##### 15:26:46 INFO - ##### Finished run-tests step (success) 15:26:46 INFO - ##### 15:26:46 INFO - Running post-run listener: _resource_record_post_run 15:26:46 INFO - Total resource usage - Wall time: 1190s; CPU: 14.0%; Read bytes: 4096; Write bytes: 548343808; Read time: 0; Write time: 464336 15:26:46 INFO - TinderboxPrint: CPU usage
13.5% 15:26:46 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 0 15:26:46 INFO - TinderboxPrint: I/O write bytes / time
548,343,808 / 464,336 15:26:46 INFO - TinderboxPrint: CPU idle
8,207.2 (86.4%) 15:26:46 INFO - TinderboxPrint: CPU user
1,232.4 (13.0%) 15:26:46 INFO - TinderboxPrint: Swap in / out
0 / 0 15:26:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 254758912; Read time: 0; Write time: 228512 15:26:46 INFO - run-tests - Wall time: 1173s; CPU: 14.0%; Read bytes: 4096; Write bytes: 291090432; Read time: 0; Write time: 235704 15:26:47 INFO - Running post-run listener: _upload_blobber_files 15:26:47 INFO - Blob upload gear active. 15:26:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:26:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:26:47 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:26:47 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:26:47 INFO - (blobuploader) - INFO - Open directory for files ... 15:26:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:26:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:26:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:48 INFO - (blobuploader) - INFO - Done attempting. 15:26:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:26:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:52 INFO - (blobuploader) - INFO - Done attempting. 15:26:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:26:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:26:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:53 INFO - (blobuploader) - INFO - Done attempting. 15:26:53 INFO - (blobuploader) - INFO - Iteration through files over. 15:26:53 INFO - Return code: 0 15:26:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:26:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:26:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/48f20dcf0757373d79d98686c61336432c5167d912febf516cf2b687fbdca83ff8403b7286f8deab41f71328617f57c3c126e11e992b370c0425224117e937eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/175588456678158771d7425b697945b9133f7eecd80c660255ade6d4dcff826d9196e5e768be88ea34c90d952a7c50ef3e6199954eec3882c530824fa719e212", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c058beaf1412c0c237eebea1b8d58e45364e4850eba70540b73cc590331b5fb5a02fe993bb7c5e8ca5d59fc0909e77a123e06cf189cbe3a8edb0c5170011ef8"} 15:26:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:26:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:26:53 INFO - Contents: 15:26:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/48f20dcf0757373d79d98686c61336432c5167d912febf516cf2b687fbdca83ff8403b7286f8deab41f71328617f57c3c126e11e992b370c0425224117e937eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/175588456678158771d7425b697945b9133f7eecd80c660255ade6d4dcff826d9196e5e768be88ea34c90d952a7c50ef3e6199954eec3882c530824fa719e212", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c058beaf1412c0c237eebea1b8d58e45364e4850eba70540b73cc590331b5fb5a02fe993bb7c5e8ca5d59fc0909e77a123e06cf189cbe3a8edb0c5170011ef8"} 15:26:53 INFO - Running post-run listener: copy_logs_to_upload_dir 15:26:53 INFO - Copying logs to upload dir... 15:26:53 INFO - mkdir: /builds/slave/test/build/upload/logs 15:26:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.042229 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-06-01 15:26:52.851922) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 15:26:52.856340) ========= 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=6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533 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/sha512/48f20dcf0757373d79d98686c61336432c5167d912febf516cf2b687fbdca83ff8403b7286f8deab41f71328617f57c3c126e11e992b370c0425224117e937eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/175588456678158771d7425b697945b9133f7eecd80c660255ade6d4dcff826d9196e5e768be88ea34c90d952a7c50ef3e6199954eec3882c530824fa719e212", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c058beaf1412c0c237eebea1b8d58e45364e4850eba70540b73cc590331b5fb5a02fe993bb7c5e8ca5d59fc0909e77a123e06cf189cbe3a8edb0c5170011ef8"} build_url:https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/GWDY-F2BQhKjWWMc1UmVyg/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/sha512/48f20dcf0757373d79d98686c61336432c5167d912febf516cf2b687fbdca83ff8403b7286f8deab41f71328617f57c3c126e11e992b370c0425224117e937eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/175588456678158771d7425b697945b9133f7eecd80c660255ade6d4dcff826d9196e5e768be88ea34c90d952a7c50ef3e6199954eec3882c530824fa719e212", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8c058beaf1412c0c237eebea1b8d58e45364e4850eba70540b73cc590331b5fb5a02fe993bb7c5e8ca5d59fc0909e77a123e06cf189cbe3a8edb0c5170011ef8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 15:26:52.887638) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:26:52.888274) ========= 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=6dbc10da6d83fee962c624be000001a8-1464818244.270270-1373352533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 15:26:52.971235) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 15:26:52.971565) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 15:26:52.971872) ========= ========= Total master_lag: 0.22 =========