builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-044 starttime: 1463783657.74 results: success (0) buildid: 20160520140829 builduid: 5a00da35498647a7b497243bdef7bec1 revision: c403ac05b8f42e44a5f7a0c98c2bf190b28706b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.743101) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.743641) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.744121) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.768832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.769100) ========= 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=574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 15:34:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-05-20 15:34:17 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115080 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.899147) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.899432) ========= 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=574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013994 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.950106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:17.950424) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c403ac05b8f42e44a5f7a0c98c2bf190b28706b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c403ac05b8f42e44a5f7a0c98c2bf190b28706b1 --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=574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 15:34:18,092 truncating revision to first 12 chars 2016-05-20 15:34:18,092 Setting DEBUG logging. 2016-05-20 15:34:18,092 attempt 1/10 2016-05-20 15:34:18,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c403ac05b8f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 15:34:18,367 unpacking tar archive at: mozilla-central-c403ac05b8f4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.570199 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:18.535068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:18.535384) ========= script_repo_revision: c403ac05b8f42e44a5f7a0c98c2bf190b28706b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:18.535801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:18.536075) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 15:34:18.584734) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 26 mins, 2 secs) (at 2016-05-20 15:34:18.585015) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:34:18 INFO - MultiFileLogger online at 20160520 15:34:18 in /builds/slave/test 15:34:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:34:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:34:18 INFO - {'append_to_log': False, 15:34:18 INFO - 'base_work_dir': '/builds/slave/test', 15:34:18 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:34:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:34:18 INFO - 'branch': 'Firefox-Non-PGO', 15:34:18 INFO - 'buildbot_json_path': 'buildprops.json', 15:34:18 INFO - 'config_files': ('talos/linux_config.py',), 15:34:18 INFO - 'default_actions': ('clobber', 15:34:18 INFO - 'read-buildbot-config', 15:34:18 INFO - 'download-and-extract', 15:34:18 INFO - 'populate-webroot', 15:34:18 INFO - 'create-virtualenv', 15:34:18 INFO - 'install', 15:34:18 INFO - 'run-tests'), 15:34:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:34:18 INFO - 'download_minidump_stackwalk': True, 15:34:18 INFO - 'download_symbols': 'ondemand', 15:34:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:34:18 INFO - 'tooltool.py': '/tools/tooltool.py', 15:34:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:34:18 INFO - '/tools/misc-python/virtualenv.py')}, 15:34:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:34:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:34:18 INFO - 'installer_path': 'installer.exe', 15:34:18 INFO - 'log_level': 'info', 15:34:18 INFO - 'log_name': 'talos', 15:34:18 INFO - 'log_to_console': True, 15:34:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:34:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:34:18 INFO - 'opt_config_files': (), 15:34:18 INFO - 'pip_index': False, 15:34:18 INFO - 'sps_profile': False, 15:34:18 INFO - 'sps_profile_interval': 0, 15:34:18 INFO - 'suite': 'dromaeojs', 15:34:18 INFO - 'system_bits': '32', 15:34:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:34:18 INFO - 'title': 'talos-linux64-ix-044', 15:34:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:34:18 INFO - 'use_talos_json': True, 15:34:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:34:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:34:18 INFO - 'work_dir': 'build'} 15:34:18 INFO - ##### 15:34:18 INFO - ##### Running clobber step. 15:34:18 INFO - ##### 15:34:18 INFO - Running pre-action listener: _resource_record_pre_action 15:34:18 INFO - Running main action method: clobber 15:34:18 INFO - rmtree: /builds/slave/test/build 15:34:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:34:19 INFO - Running post-action listener: _resource_record_post_action 15:34:19 INFO - ##### 15:34:19 INFO - ##### Finished clobber step (success) 15:34:19 INFO - ##### 15:34:19 INFO - ##### 15:34:19 INFO - ##### Running read-buildbot-config step. 15:34:19 INFO - ##### 15:34:19 INFO - Running pre-action listener: _resource_record_pre_action 15:34:19 INFO - Running main action method: read_buildbot_config 15:34:19 INFO - Using buildbot properties: 15:34:19 INFO - { 15:34:19 INFO - "project": "", 15:34:19 INFO - "product": "firefox", 15:34:19 INFO - "script_repo_revision": "production", 15:34:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:34:19 INFO - "repository": "", 15:34:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:34:19 INFO - "buildid": "20160520140829", 15:34:19 INFO - "pgo_build": "False", 15:34:19 INFO - "basedir": "/builds/slave/test", 15:34:19 INFO - "buildnumber": 90, 15:34:19 INFO - "slavename": "talos-linux64-ix-044", 15:34:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:34:19 INFO - "platform": "ubuntu64_hw", 15:34:19 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:34:19 INFO - "branch": "mozilla-central", 15:34:19 INFO - "repo_path": "mozilla-central", 15:34:19 INFO - "revision": "c403ac05b8f42e44a5f7a0c98c2bf190b28706b1", 15:34:19 INFO - "stage_platform": "linux64", 15:34:19 INFO - "builduid": "5a00da35498647a7b497243bdef7bec1", 15:34:19 INFO - "slavebuilddir": "test" 15:34:19 INFO - } 15:34:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:34:19 INFO - Running post-action listener: _resource_record_post_action 15:34:19 INFO - ##### 15:34:19 INFO - ##### Finished read-buildbot-config step (success) 15:34:19 INFO - ##### 15:34:19 INFO - ##### 15:34:19 INFO - ##### Running download-and-extract step. 15:34:19 INFO - ##### 15:34:19 INFO - Running pre-action listener: _resource_record_pre_action 15:34:19 INFO - Running main action method: download_and_extract 15:34:19 INFO - mkdir: /builds/slave/test/build/tests 15:34: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:34:19 INFO - https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:34:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:34:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:34:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:34:26 INFO - Downloaded 1361 bytes. 15:34:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:34:26 INFO - Using the following test package requirements: 15:34:26 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:34:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:34:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:34:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'jsshell-linux-x86_64.zip'], 15:34:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:34:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:34:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:34:26 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:34:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:34:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:34:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:34:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:34:26 INFO - https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:34:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:34:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:34:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:34:30 INFO - Downloaded 22814329 bytes. 15:34:30 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:34:30 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:34:31 INFO - Return code: 0 15:34:31 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:34:31 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:34:31 INFO - https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:34:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:34:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:34:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:34:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:38:03 INFO - Downloaded 22814329 bytes. 15:38:03 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:38:03 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:38:05 INFO - Return code: 0 15:38:05 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:38:05 INFO - https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:38:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:38:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:38:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:38:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:39:44 INFO - Downloaded 10840359 bytes. 15:39:44 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:39:44 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:39:44 INFO - Return code: 0 15:39:44 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:39:44 INFO - https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:39:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:39:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:39:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:39:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CmsNtxq1REaRrDf8IZFQvg/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:39:46 INFO - Downloaded 54811848 bytes. 15:39:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:39:46 INFO - mkdir: /builds/slave/test/properties 15:39:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:46 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:46 INFO - Contents: 15:39:46 INFO - build_url:https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:39:48 INFO - Running post-action listener: _resource_record_post_action 15:39:48 INFO - Running post-action listener: set_extra_try_arguments 15:39:48 INFO - ##### 15:39:48 INFO - ##### Finished download-and-extract step (success) 15:39:48 INFO - ##### 15:39:48 INFO - ##### 15:39:48 INFO - ##### Running populate-webroot step. 15:39:48 INFO - ##### 15:39:48 INFO - Running pre-action listener: _resource_record_pre_action 15:39:48 INFO - Running main action method: populate_webroot 15:39:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:39:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:39:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:39:48 INFO - '--tppagecycles', 15:39:48 INFO - '7'], 15:39:48 INFO - 'tests': ['tsvgm']}}, 15:39:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:39:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:39:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['damp', 'tps']}, 15:39:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'tests': ['damp', 'tps']}, 15:39:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['dromaeo_dom']}, 15:39:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['a11yr', 15:39:48 INFO - 'ts_paint', 15:39:48 INFO - 'tpaint', 15:39:48 INFO - 'sessionrestore', 15:39:48 INFO - 'sessionrestore_no_auto_restore', 15:39:48 INFO - 'tabpaint']}, 15:39:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:48 INFO - 'ts_paint', 15:39:48 INFO - 'tpaint', 15:39:48 INFO - 'sessionrestore', 15:39:48 INFO - 'sessionrestore_no_auto_restore', 15:39:48 INFO - 'tabpaint']}, 15:39:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:39:48 INFO - 'ts_paint', 15:39:48 INFO - 'tpaint', 15:39:48 INFO - 'sessionrestore', 15:39:48 INFO - 'sessionrestore_no_auto_restore', 15:39:48 INFO - 'tabpaint']}, 15:39:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:39:48 INFO - 'ts_paint', 15:39:48 INFO - 'tpaint', 15:39:48 INFO - 'sessionrestore', 15:39:48 INFO - 'sessionrestore_no_auto_restore', 15:39:48 INFO - 'tabpaint']}, 15:39:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['a11yr', 15:39:48 INFO - 'ts_paint', 15:39:48 INFO - 'tpaint', 15:39:48 INFO - 'sessionrestore', 15:39:48 INFO - 'sessionrestore_no_auto_restore', 15:39:48 INFO - 'tabpaint']}, 15:39:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['a11yr', 15:39:48 INFO - 'ts_paint', 15:39:48 INFO - 'tpaint', 15:39:48 INFO - 'sessionrestore', 15:39:48 INFO - 'sessionrestore_no_auto_restore', 15:39:48 INFO - 'tabpaint']}, 15:39:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['tsvgx', 15:39:48 INFO - 'tsvgr_opacity', 15:39:48 INFO - 'tart', 15:39:48 INFO - 'tscrollx', 15:39:48 INFO - 'cart']}, 15:39:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:48 INFO - 'tsvgr_opacity', 15:39:48 INFO - 'tart', 15:39:48 INFO - 'tscrollx', 15:39:48 INFO - 'cart']}, 15:39:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'talos_options': ['--disable-e10s'], 15:39:48 INFO - 'tests': ['tp5o']}, 15:39:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'tests': ['tp5o']}, 15:39:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'talos_options': ['--disable-e10s', 15:39:48 INFO - '--xperf_path', 15:39:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:48 INFO - 'tests': ['tp5n']}, 15:39:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:48 INFO - 'talos_options': ['--xperf_path', 15:39:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:48 INFO - 'tests': ['tp5n']}}, 15:39:48 INFO - 'talos.zip': {'path': '', 15:39:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:39:48 INFO - Running post-action listener: _resource_record_post_action 15:39:48 INFO - ##### 15:39:48 INFO - ##### Finished populate-webroot step (success) 15:39:48 INFO - ##### 15:39:48 INFO - ##### 15:39:48 INFO - ##### Running create-virtualenv step. 15:39:48 INFO - ##### 15:39:48 INFO - Running pre-action listener: _resource_record_pre_action 15:39:48 INFO - Running main action method: create_virtualenv 15:39:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:48 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:39:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:39:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:48 INFO - Using real prefix '/usr' 15:39:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:51 INFO - Installing pip.................done. 15:39:51 INFO - Return code: 0 15:39:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:51 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:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:51 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:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:51 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:39:51 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:39:51 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:39:51 INFO - Using env: {'DISPLAY': ':0', 15:39:51 INFO - 'HOME': '/home/cltbld', 15:39:51 INFO - 'LANG': 'en_US.UTF-8', 15:39:51 INFO - 'LANGUAGE': 'en_US:en', 15:39:51 INFO - 'LOGNAME': 'cltbld', 15:39:51 INFO - 'MAIL': '/var/mail/cltbld', 15:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:51 INFO - 'MOZ_NO_REMOTE': '1', 15:39:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:51 INFO - 'NO_EM_RESTART': '1', 15:39:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:51 INFO - 'PWD': '/builds/slave/test', 15:39:51 INFO - 'SHELL': '/bin/bash', 15:39:51 INFO - 'SHLVL': '1', 15:39:51 INFO - 'TERM': 'linux', 15:39:51 INFO - 'TMOUT': '86400', 15:39:51 INFO - 'USER': 'cltbld', 15:39:51 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:51 INFO - '_': '/tools/buildbot/bin/python'} 15:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:51 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:39:51 INFO - Cleaning up... 15:39:51 INFO - Return code: 0 15:39:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:51 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:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:51 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:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:51 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:39:51 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:39:51 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:39:51 INFO - Using env: {'DISPLAY': ':0', 15:39:51 INFO - 'HOME': '/home/cltbld', 15:39:51 INFO - 'LANG': 'en_US.UTF-8', 15:39:51 INFO - 'LANGUAGE': 'en_US:en', 15:39:51 INFO - 'LOGNAME': 'cltbld', 15:39:51 INFO - 'MAIL': '/var/mail/cltbld', 15:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:51 INFO - 'MOZ_NO_REMOTE': '1', 15:39:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:51 INFO - 'NO_EM_RESTART': '1', 15:39:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:51 INFO - 'PWD': '/builds/slave/test', 15:39:51 INFO - 'SHELL': '/bin/bash', 15:39:51 INFO - 'SHLVL': '1', 15:39:51 INFO - 'TERM': 'linux', 15:39:51 INFO - 'TMOUT': '86400', 15:39:51 INFO - 'USER': 'cltbld', 15:39:51 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:51 INFO - '_': '/tools/buildbot/bin/python'} 15:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:51 INFO - Downloading/unpacking psutil>=3.1.1 15:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:53 INFO - Installing collected packages: psutil 15:39:53 INFO - Running setup.py install for psutil 15:39:53 INFO - building 'psutil._psutil_linux' extension 15:39:53 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:39:53 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:39:53 INFO - building 'psutil._psutil_posix' extension 15:39:53 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:39:53 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:39:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:53 INFO - Successfully installed psutil 15:39:53 INFO - Cleaning up... 15:39:54 INFO - Return code: 0 15:39:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:54 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:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:54 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:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:54 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:39:54 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:39:54 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:39:54 INFO - Using env: {'DISPLAY': ':0', 15:39:54 INFO - 'HOME': '/home/cltbld', 15:39:54 INFO - 'LANG': 'en_US.UTF-8', 15:39:54 INFO - 'LANGUAGE': 'en_US:en', 15:39:54 INFO - 'LOGNAME': 'cltbld', 15:39:54 INFO - 'MAIL': '/var/mail/cltbld', 15:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:54 INFO - 'MOZ_NO_REMOTE': '1', 15:39:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:54 INFO - 'NO_EM_RESTART': '1', 15:39:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:54 INFO - 'PWD': '/builds/slave/test', 15:39:54 INFO - 'SHELL': '/bin/bash', 15:39:54 INFO - 'SHLVL': '1', 15:39:54 INFO - 'TERM': 'linux', 15:39:54 INFO - 'TMOUT': '86400', 15:39:54 INFO - 'USER': 'cltbld', 15:39:54 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:54 INFO - '_': '/tools/buildbot/bin/python'} 15:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:54 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:39:54 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:39:54 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:39:54 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:39:54 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:39:54 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:39:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:56 INFO - Installing collected packages: mozsystemmonitor 15:39:56 INFO - Running setup.py install for mozsystemmonitor 15:39:56 INFO - Successfully installed mozsystemmonitor 15:39:56 INFO - Cleaning up... 15:39:56 INFO - Return code: 0 15:39:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:56 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:39:56 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:39:56 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:39:56 INFO - Using env: {'DISPLAY': ':0', 15:39:56 INFO - 'HOME': '/home/cltbld', 15:39:56 INFO - 'LANG': 'en_US.UTF-8', 15:39:56 INFO - 'LANGUAGE': 'en_US:en', 15:39:56 INFO - 'LOGNAME': 'cltbld', 15:39:56 INFO - 'MAIL': '/var/mail/cltbld', 15:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:56 INFO - 'MOZ_NO_REMOTE': '1', 15:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:56 INFO - 'NO_EM_RESTART': '1', 15:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:56 INFO - 'PWD': '/builds/slave/test', 15:39:56 INFO - 'SHELL': '/bin/bash', 15:39:56 INFO - 'SHLVL': '1', 15:39:56 INFO - 'TERM': 'linux', 15:39:56 INFO - 'TMOUT': '86400', 15:39:56 INFO - 'USER': 'cltbld', 15:39:56 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:56 INFO - '_': '/tools/buildbot/bin/python'} 15:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:56 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:39:56 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:39:56 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:39:56 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:39:56 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:39:56 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:39:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:58 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:39:58 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:39:58 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:39:58 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:39:58 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:39:58 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:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:58 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:39:58 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:39:58 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:39:58 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:39:58 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:39:58 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:39:59 INFO - Downloading docopt-0.6.1.tar.gz 15:39:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:59 INFO - Running setup.py install for blobuploader 15:39:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:59 INFO - Running setup.py install for requests 15:39:59 INFO - Running setup.py install for docopt 15:39:59 INFO - Successfully installed blobuploader requests docopt 15:39:59 INFO - Cleaning up... 15:39:59 INFO - Return code: 0 15:39:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:59 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:39:59 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:39:59 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:39:59 INFO - Using env: {'DISPLAY': ':0', 15:39:59 INFO - 'HOME': '/home/cltbld', 15:39:59 INFO - 'LANG': 'en_US.UTF-8', 15:39:59 INFO - 'LANGUAGE': 'en_US:en', 15:39:59 INFO - 'LOGNAME': 'cltbld', 15:39:59 INFO - 'MAIL': '/var/mail/cltbld', 15:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:59 INFO - 'MOZ_NO_REMOTE': '1', 15:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:59 INFO - 'NO_EM_RESTART': '1', 15:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:59 INFO - 'PWD': '/builds/slave/test', 15:39:59 INFO - 'SHELL': '/bin/bash', 15:39:59 INFO - 'SHLVL': '1', 15:39:59 INFO - 'TERM': 'linux', 15:39:59 INFO - 'TMOUT': '86400', 15:39:59 INFO - 'USER': 'cltbld', 15:39:59 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:59 INFO - '_': '/tools/buildbot/bin/python'} 15:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:59 INFO - Running setup.py (path:/tmp/pip-D7RVH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:00 INFO - Running setup.py (path:/tmp/pip-Rgnfbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:00 INFO - Running setup.py (path:/tmp/pip-br0RIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:00 INFO - Running setup.py (path:/tmp/pip-8l5Dxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:00 INFO - Running setup.py (path:/tmp/pip-hZ7Q2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:00 INFO - Running setup.py (path:/tmp/pip-fe5HLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:00 INFO - Running setup.py (path:/tmp/pip-ZZ5cSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:00 INFO - Running setup.py (path:/tmp/pip-5_owxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:00 INFO - Running setup.py (path:/tmp/pip-F0NypH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:00 INFO - Running setup.py (path:/tmp/pip-wBlwBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:00 INFO - Running setup.py (path:/tmp/pip-ano5dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:00 INFO - Running setup.py (path:/tmp/pip-XZIBEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:00 INFO - Running setup.py (path:/tmp/pip-o_BWKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:00 INFO - Running setup.py (path:/tmp/pip-VSed4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:00 INFO - Running setup.py (path:/tmp/pip-9sd9Sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:01 INFO - Running setup.py (path:/tmp/pip-lzUznU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:01 INFO - Running setup.py (path:/tmp/pip-D_oK6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:01 INFO - Running setup.py install for manifestparser 15:40:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozcrash 15:40:01 INFO - Running setup.py install for mozdebug 15:40:01 INFO - Running setup.py install for mozdevice 15:40:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozfile 15:40:01 INFO - Running setup.py install for mozhttpd 15:40:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozinfo 15:40:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozInstall 15:40:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Running setup.py install for mozleak 15:40:02 INFO - Running setup.py install for mozlog 15:40:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Running setup.py install for moznetwork 15:40:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Running setup.py install for mozprocess 15:40:02 INFO - Running setup.py install for mozprofile 15:40:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Running setup.py install for mozrunner 15:40:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Running setup.py install for mozscreenshot 15:40:02 INFO - Running setup.py install for moztest 15:40:02 INFO - Running setup.py install for mozversion 15:40:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:02 INFO - Cleaning up... 15:40:03 INFO - Return code: 0 15:40:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:03 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:40:03 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:40:03 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:40:03 INFO - Using env: {'DISPLAY': ':0', 15:40:03 INFO - 'HOME': '/home/cltbld', 15:40:03 INFO - 'LANG': 'en_US.UTF-8', 15:40:03 INFO - 'LANGUAGE': 'en_US:en', 15:40:03 INFO - 'LOGNAME': 'cltbld', 15:40:03 INFO - 'MAIL': '/var/mail/cltbld', 15:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:03 INFO - 'MOZ_NO_REMOTE': '1', 15:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:03 INFO - 'NO_EM_RESTART': '1', 15:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:03 INFO - 'PWD': '/builds/slave/test', 15:40:03 INFO - 'SHELL': '/bin/bash', 15:40:03 INFO - 'SHLVL': '1', 15:40:03 INFO - 'TERM': 'linux', 15:40:03 INFO - 'TMOUT': '86400', 15:40:03 INFO - 'USER': 'cltbld', 15:40:03 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:03 INFO - '_': '/tools/buildbot/bin/python'} 15:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:03 INFO - Running setup.py (path:/tmp/pip-xeIkBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:03 INFO - Running setup.py (path:/tmp/pip-a3quGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:03 INFO - Running setup.py (path:/tmp/pip-_dBnD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:03 INFO - Running setup.py (path:/tmp/pip-RQ5JW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:03 INFO - Running setup.py (path:/tmp/pip-y9SnEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:03 INFO - Running setup.py (path:/tmp/pip-N1sBHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:03 INFO - Running setup.py (path:/tmp/pip-gsQQFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:03 INFO - Running setup.py (path:/tmp/pip-5IiKrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:03 INFO - Running setup.py (path:/tmp/pip-fGjLhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:03 INFO - Running setup.py (path:/tmp/pip-dJ_Ism-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:03 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:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:03 INFO - Running setup.py (path:/tmp/pip-lv5JJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:04 INFO - Running setup.py (path:/tmp/pip-YOGpyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:04 INFO - Running setup.py (path:/tmp/pip-F_hIfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:04 INFO - Running setup.py (path:/tmp/pip-Zj0qc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:04 INFO - Running setup.py (path:/tmp/pip-WQtsb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:04 INFO - Running setup.py (path:/tmp/pip-gHKn1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:04 INFO - Running setup.py (path:/tmp/pip-3Bars4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:04 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:40:04 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:40:04 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:40:04 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:40:04 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:40:04 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:40:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:06 INFO - Downloading blessings-1.6.tar.gz 15:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:06 INFO - Installing collected packages: blessings 15:40:06 INFO - Running setup.py install for blessings 15:40:06 INFO - Successfully installed blessings 15:40:06 INFO - Cleaning up... 15:40:06 INFO - Return code: 0 15:40:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:06 INFO - Reading from file tmpfile_stdout 15:40:06 INFO - Current package versions: 15:40:06 INFO - argparse == 1.2.1 15:40:06 INFO - blessings == 1.6 15:40:06 INFO - blobuploader == 1.2.4 15:40:06 INFO - docopt == 0.6.1 15:40:06 INFO - manifestparser == 1.1 15:40:06 INFO - mozInstall == 1.12 15:40:06 INFO - mozcrash == 0.17 15:40:06 INFO - mozdebug == 0.1 15:40:06 INFO - mozdevice == 0.48 15:40:06 INFO - mozfile == 1.2 15:40:06 INFO - mozhttpd == 0.7 15:40:06 INFO - mozinfo == 0.9 15:40:06 INFO - mozleak == 0.1 15:40:06 INFO - mozlog == 3.2 15:40:06 INFO - moznetwork == 0.27 15:40:06 INFO - mozprocess == 0.22 15:40:06 INFO - mozprofile == 0.28 15:40:06 INFO - mozrunner == 6.11 15:40:06 INFO - mozscreenshot == 0.1 15:40:06 INFO - mozsystemmonitor == 0.3 15:40:06 INFO - moztest == 0.7 15:40:06 INFO - mozversion == 1.4 15:40:06 INFO - psutil == 3.1.1 15:40:06 INFO - requests == 1.2.3 15:40:06 INFO - wsgiref == 0.1.2 15:40:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:06 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:40:06 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:40:06 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:40:06 INFO - Using env: {'DISPLAY': ':0', 15:40:06 INFO - 'HOME': '/home/cltbld', 15:40:06 INFO - 'LANG': 'en_US.UTF-8', 15:40:06 INFO - 'LANGUAGE': 'en_US:en', 15:40:06 INFO - 'LOGNAME': 'cltbld', 15:40:06 INFO - 'MAIL': '/var/mail/cltbld', 15:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:06 INFO - 'MOZ_NO_REMOTE': '1', 15:40:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:06 INFO - 'NO_EM_RESTART': '1', 15:40:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:06 INFO - 'PWD': '/builds/slave/test', 15:40:06 INFO - 'SHELL': '/bin/bash', 15:40:06 INFO - 'SHLVL': '1', 15:40:06 INFO - 'TERM': 'linux', 15:40:06 INFO - 'TMOUT': '86400', 15:40:06 INFO - 'USER': 'cltbld', 15:40:06 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:06 INFO - '_': '/tools/buildbot/bin/python'} 15:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 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:40:07 INFO - Cleaning up... 15:40:07 INFO - Return code: 0 15:40:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:07 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 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:40:07 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:40:07 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:40:07 INFO - Using env: {'DISPLAY': ':0', 15:40:07 INFO - 'HOME': '/home/cltbld', 15:40:07 INFO - 'LANG': 'en_US.UTF-8', 15:40:07 INFO - 'LANGUAGE': 'en_US:en', 15:40:07 INFO - 'LOGNAME': 'cltbld', 15:40:07 INFO - 'MAIL': '/var/mail/cltbld', 15:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:07 INFO - 'MOZ_NO_REMOTE': '1', 15:40:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:07 INFO - 'NO_EM_RESTART': '1', 15:40:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:07 INFO - 'PWD': '/builds/slave/test', 15:40:07 INFO - 'SHELL': '/bin/bash', 15:40:07 INFO - 'SHLVL': '1', 15:40:07 INFO - 'TERM': 'linux', 15:40:07 INFO - 'TMOUT': '86400', 15:40:07 INFO - 'USER': 'cltbld', 15:40:07 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:07 INFO - '_': '/tools/buildbot/bin/python'} 15:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:07 INFO - Downloading/unpacking jsonschema 15:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:09 INFO - Downloading/unpacking functools32 (from jsonschema) 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:09 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:09 INFO - warning: no files found matching '*.txt' 15:40:09 INFO - no previously-included directories found matching 'build' 15:40:09 INFO - no previously-included directories found matching 'dist' 15:40:09 INFO - no previously-included directories found matching '.git*' 15:40:09 INFO - Installing collected packages: jsonschema, functools32 15:40:09 INFO - Running setup.py install for functools32 15:40:09 INFO - warning: no files found matching '*.txt' 15:40:09 INFO - no previously-included directories found matching 'build' 15:40:09 INFO - no previously-included directories found matching 'dist' 15:40:09 INFO - no previously-included directories found matching '.git*' 15:40:09 INFO - Successfully installed jsonschema functools32 15:40:09 INFO - Cleaning up... 15:40:09 INFO - Return code: 0 15:40:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98af0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, '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': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', '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:40:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:40:09 INFO - Using env: {'DISPLAY': ':0', 15:40:09 INFO - 'HOME': '/home/cltbld', 15:40:09 INFO - 'LANG': 'en_US.UTF-8', 15:40:09 INFO - 'LANGUAGE': 'en_US:en', 15:40:09 INFO - 'LOGNAME': 'cltbld', 15:40:09 INFO - 'MAIL': '/var/mail/cltbld', 15:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:09 INFO - 'MOZ_NO_REMOTE': '1', 15:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:09 INFO - 'NO_EM_RESTART': '1', 15:40:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:09 INFO - 'PWD': '/builds/slave/test', 15:40:09 INFO - 'SHELL': '/bin/bash', 15:40:09 INFO - 'SHLVL': '1', 15:40:09 INFO - 'TERM': 'linux', 15:40:09 INFO - 'TMOUT': '86400', 15:40:09 INFO - 'USER': 'cltbld', 15:40:09 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:09 INFO - '_': '/tools/buildbot/bin/python'} 15:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:09 INFO - Downloading/unpacking flake8 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:40:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:40:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:40:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:40:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:40:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:40:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:40:12 INFO - no previously-included directories found matching 'docs/_build' 15:40:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:40:12 INFO - Running setup.py install for pep8 15:40:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:40:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:40:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:40:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:40:12 INFO - no previously-included directories found matching 'docs/_build' 15:40:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:40:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:40:12 INFO - Cleaning up... 15:40:12 INFO - Return code: 0 15:40:12 INFO - Running post-action listener: _resource_record_post_action 15:40:12 INFO - Running post-action listener: _start_resource_monitoring 15:40:12 INFO - Starting resource monitoring. 15:40:12 INFO - ##### 15:40:12 INFO - ##### Finished create-virtualenv step (success) 15:40:12 INFO - ##### 15:40:12 INFO - ##### 15:40:12 INFO - ##### Running install step. 15:40:12 INFO - ##### 15:40:12 INFO - Running pre-action listener: _resource_record_pre_action 15:40:12 INFO - Running main action method: install 15:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:12 INFO - Reading from file tmpfile_stdout 15:40:12 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:12 INFO - Reading from file tmpfile_stdout 15:40:12 INFO - Output received: 15:40:12 INFO - Usage: mozinstall [options] installer 15:40:13 INFO - Options: 15:40:13 INFO - -h, --help show this help message and exit 15:40:13 INFO - -d DEST, --destination=DEST 15:40:13 INFO - Directory to install application into. [default: 15:40:13 INFO - "/builds/slave/test"] 15:40:13 INFO - --app=APP Application being installed. [default: firefox] 15:40:13 INFO - mkdir: /builds/slave/test/build/application 15:40:13 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:40:13 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:40:28 INFO - Reading from file tmpfile_stdout 15:40:28 INFO - Output received: 15:40:28 INFO - /builds/slave/test/build/application/firefox/firefox 15:40:28 INFO - Running post-action listener: _resource_record_post_action 15:40:28 INFO - ##### 15:40:28 INFO - ##### Finished install step (success) 15:40:28 INFO - ##### 15:40:28 INFO - ##### 15:40:28 INFO - ##### Running run-tests step. 15:40:28 INFO - ##### 15:40:28 INFO - Running pre-action listener: _resource_record_pre_action 15:40:28 INFO - Running main action method: run_tests 15:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:28 INFO - Python 2.7.3 15:40:28 INFO - Return code: 0 15:40:28 INFO - grabbing minidump binary from tooltool 15:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:28 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 0x13409c0>, '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 0x13418c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1341d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:28 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:40:28 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:40:28 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:40:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:28 INFO - Return code: 0 15:40:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:40:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:40:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:40:29 INFO - Return code: 0 15:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:40:29 INFO - Using env: {'DISPLAY': ':0', 15:40:29 INFO - 'HOME': '/home/cltbld', 15:40:29 INFO - 'LANG': 'en_US.UTF-8', 15:40:29 INFO - 'LANGUAGE': 'en_US:en', 15:40:29 INFO - 'LOGNAME': 'cltbld', 15:40:29 INFO - 'MAIL': '/var/mail/cltbld', 15:40:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:29 INFO - 'MOZ_NO_REMOTE': '1', 15:40:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:29 INFO - 'NO_EM_RESTART': '1', 15:40:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:29 INFO - 'PWD': '/builds/slave/test', 15:40:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:29 INFO - 'SHELL': '/bin/bash', 15:40:29 INFO - 'SHLVL': '1', 15:40:29 INFO - 'TERM': 'linux', 15:40:29 INFO - 'TMOUT': '86400', 15:40:29 INFO - 'USER': 'cltbld', 15:40:29 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244', 15:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:29 INFO - '_': '/tools/buildbot/bin/python'} 15:40:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:40:29 INFO - mozversion application_buildid: 20160520140829 15:40:29 INFO - mozversion application_changeset: c403ac05b8f42e44a5f7a0c98c2bf190b28706b1 15:40:29 INFO - mozversion application_display_name: Nightly 15:40:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:29 INFO - mozversion application_name: Firefox 15:40:29 INFO - mozversion application_remotingname: firefox 15:40:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:40:29 INFO - mozversion application_vendor: Mozilla 15:40:29 INFO - mozversion application_version: 49.0a1 15:40:29 INFO - mozversion platform_buildid: 20160520140829 15:40:29 INFO - mozversion platform_changeset: c403ac05b8f42e44a5f7a0c98c2bf190b28706b1 15:40:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:40:29 INFO - mozversion platform_version: 49.0a1 15:40:29 INFO - using testdate: 1463784029 15:40:29 INFO - actual date: 1463784029 15:40:29 INFO - starting webserver on 'localhost:42289' 15:40:29 INFO - SUITE-START | Running 2 tests 15:40:29 INFO - TEST-START | dromaeo_css 15:40:29 INFO - operating with platform_type : linux_ 15:40:29 INFO - Initialising browser for dromaeo_css test... 15:40:29 INFO - TEST-INFO | started process 6266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp835it0/profile http://localhost:42289/getInfo.html) 15:40:34 INFO - PROCESS | 6266 | __metrics Screen width/height:1600/1200 15:40:34 INFO - PROCESS | 6266 | colorDepth:24 15:40:34 INFO - PROCESS | 6266 | Browser inner width/height: 1024/697 15:40:34 INFO - PROCESS | 6266 | __metrics 15:40:35 ERROR - PROCESS | 6266 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:40:36 INFO - TEST-INFO | 6266: exit 0 15:40:36 INFO - Browser initialized. 15:40:36 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:36 INFO - TEST-INFO | started process 6349 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp835it0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:48 INFO - PROCESS | 6349 | RSS: Main: 154726400 15:40:48 INFO - PROCESS | 6349 | 15:41:40 INFO - PROCESS | 6349 | 1463784100111 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:43:51 INFO - PROCESS | 6349 | Cycle 1(1): loaded http://localhost:42289/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42289/tests/dromaeo/cssquery-ext.html) 15:43:52 INFO - PROCESS | 6349 | RSS: Main: 236048384 15:43:52 INFO - PROCESS | 6349 | 15:46:55 INFO - PROCESS | 6349 | Cycle 1(1): loaded http://localhost:42289/tests/dromaeo/cssquery-ext.html (next: http://localhost:42289/tests/dromaeo/cssquery-jquery.html) 15:46:55 INFO - PROCESS | 6349 | RSS: Main: 237367296 15:46:55 INFO - PROCESS | 6349 | 15:49:57 INFO - PROCESS | 6349 | Cycle 1(1): loaded http://localhost:42289/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42289/tests/dromaeo/cssquery-mootools.html) 15:49:58 INFO - PROCESS | 6349 | RSS: Main: 250679296 15:49:58 INFO - PROCESS | 6349 | 15:53:00 INFO - PROCESS | 6349 | Cycle 1(1): loaded http://localhost:42289/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42289/tests/dromaeo/cssquery-prototype.html) 15:53:01 INFO - PROCESS | 6349 | RSS: Main: 234840064 15:53:01 INFO - PROCESS | 6349 | 15:56:04 INFO - PROCESS | 6349 | Cycle 1(1): loaded http://localhost:42289/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42289/tests/dromaeo/cssquery-yui.html) 15:56:04 INFO - PROCESS | 6349 | RSS: Main: 215113728 15:56:04 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | Cycle 1(1): loaded http://localhost:42289/tests/dromaeo/cssquery-yui.html (next: http://localhost:42289/tests/dromaeo/cssquery-dojo.html) 15:59:07 INFO - PROCESS | 6349 | RSS: Main: 271474688 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | __start_tp_report 15:59:07 INFO - PROCESS | 6349 | _x_x_mozilla_page_load 15:59:07 INFO - PROCESS | 6349 | _x_x_mozilla_page_load_details 15:59:07 INFO - PROCESS | 6349 | |i|pagename|runs| 15:59:07 INFO - PROCESS | 6349 | |0;dojo.html;2684.382592003839;2688.408550118973;2725.8228215166014;2733.630959820425;2760.406512599791;4602.930956035654;4652;4660.440747110347;4745.715257084573;4783.5216478352195;4661.953380466194;4664.0205556833025;4664.090502352043;4703.05938812238;4710.670253082286;4131.132462182945;4340.761258130801;4392.516823149467;4419.315006174048;4460.598546130847;4371;4372.519022907493;4399.38408622793;4417.823287068513;4465.598096171344;4250.341197114452;4306.397104405386;4334.414853994702;4373.453318335208;4380.912381752347;4348.782560871975;4371.825126994916;4373.912521749579;4419.558044195587;4420.248557745191;4363.498197707241;4388.71473354231;4404.788683112138;4454.510003899601;4458.264386376244;4540.954590454086;4592.586667199935;4626.8895465088135;4659.906801863944;4666.626669866402;5112.335396398445;5130.20481825314;5133.460986596391;5136.845894623168;5144.4083930348115;5148.6910785353;5157.045946499909;5162.638615296931;5168.586513078944;5174.301469301671;4629.976850115761;4690.085433340534;4697.131030759252;4739.739314337676;4805.8077676892535;5166.509181627739;5195.948040519622;5198.4801519847715;5203.661761985459;5249.685018898802;4853.611711063107;5037.924431133536;5043.672161309503;5046.4953504649975;5050.3939527256225;5032.72320022395;5086.64393492452;5097.439281679012;5102.566281866083;5117.437081920985;5009.849704508871;5043.672161309503;5048.697078175322;5051.595872330205;5110.540051395318;4850.708957462563;4936.555709986119;5050.191969284897;5063.417706963673;5065.518775716301;4309.741415515079;4330.17726631939;4331.025519258142;4369.453818272716;4375.824967001348;3780.905477363088;3798.031501967036;3803.980980095137;3841.692664586826;3849.4418309344987;4229.851955181615;4273.380359847804;4301.78491075445;4314.245007123804;4329.848455304113;5138.717370544656;5146.536811686891;5159.303494028333;5168.095583272912;5220.817271395483;4355.412019377407;4363.127374525107;4364.912701745948;4402.581754733295;4408.823647054146;4436.689431739747;4528.230200865861;4573.245414506636;4616.515265897099;4626.167289887852;4749.026449577897;4775.665703400868;4780.043991201843;4816.470188279286;4831.0096430232425;5107;5110.97444512775;5114.360704911886;5116.462771408948;5116.795328186831;4597.632189424771;4686.554777296152;4713.528647135397;4723.291506274087;4797.688150270221;4987.326710894128;4996.075725990703;5004.1492946198505;5005.649604527794;5039.420466646383;5014.197728363444;5059.215821547666;5059.519345662156;5064.063148317573;5114.4629813869005;4852.029594081268;4908.141075311878;4926.236433352836;4956.207006878882;4976.925346119883;4942.55517003457;4984.501549844987;4985.800567977385;4986.775595098156;5028.321176641253;4694.953050469451;4878.121938051043;4886.291487734187;4894.510548945077;4937.604991600591;4902.950970490392;4947.678400903929;4957.380327459067;4960.379952505937;4961.181405068123;4864.270359446112;4871.147549178951;4874.756262186947;4880.536349046835;4922.8769280768265 15:59:07 INFO - PROCESS | 6349 | |1;ext.html;585.7100735136107;590.9658098327926;594.9791757288493;601.1703848688808;613.8526753579142;40107.19785604286;41263.79368103159;41362.793186034105;45543.77228113855;45729.542704572945;41173.794131029375;41791.79104104475;42271.57728422715;42317.57682423175;42337.57662423375;7686.615669216545;7856.1358250592675;7864.331531819774;7874.763757087268;7875.566843823616;7775.650095745705;7866.292033716964;7875.921240787576;7877.881831772528;7881.093674227452;7823.608819559027;7878.960605196966;7901.170377110407;7903.0911445183965;7903.683852645916;7824.843503129934;7830.765077047698;7861.606919654052;7884.842303153962;7885.802854928644;7571;7612.314891659722;7628.199039100926;7656.770296891046;7659.770206893746;38996.61003389958;39351.8032409839;39398.21203575913;39406.802965984985;39507.802460987514;2879.8128121672025;2986.044465770954;3296.2912973710527;3677.6506231907924;3755.605661405568;28021.299467513476;28280.434391312265;30648.540271895938;31463.685363146305;32200.838995805105;3465.9826700866333;3468.8612455501748;3484.0914412103098;3549.3256281306726;3609.314230296235;272374;279422;279637.60181199166;279935;280106;57866.4213357861;59161.70419147877;59237;59272.70363648154;59327.406725933055;52729.736351317995;59837.70081149566;61072.08391874126;61196;67534.66232668805;13241.337933103307;13302.534411295752;13335.599932001862;13351.866481335257;13352.599422017354;8864.64541418349;9828.705138845846;9895.901040989498;9918.355306905029;9918.355306905029;18852.454918585816;18953;19543.413697589094;19703;19814.900925495567;7176.282371762782;7244.166920804069;7383.375657355373;7390.778276651709;7461.328480436787;823.4524041512327;862.2153840005565;879.2833840420037;930.6696122876417;1021.9116640777548;27591.448171036467;28989.56515652267;29165.52531314582;29410.558841617392;29474.263143421587;929.1776777551916;931.064280398209;948.5257371314343;961.403929563675;1068.3376306689902;15251.161186134868;15517.767233491506;15683.215839207995;15725.370985160465;15806.36774529029;387544;390028;390164;390279;393161;52685;53067.46932530702;53090.73454632702;53097.469025309256;53200;1088.0914436445498;1199.6401079676236;1206.6380085974172;1229.7909355409602;1245.250423155468;28668.569971450026;29791.55312670355;29933.55099673463;30136.84931575328;30574.08277751671;43321.56678433175;43421.565784343016;43936.12127757491;43958.12083758371;43968.34047489354;311106;314007;314558;314641;315340;22736.886315568318;24965.750342496343;25504.617430738926;25533.23400297994;26946.32634184161;41636;41815.7909210452;42234;42296.788516057226;42840.14319713651;35266.82366588253;36637.26725465422;36787;37149.25701486009;37481;36190.8190459046;36350.81824590966;36392.636073638925;36533.45199821952;36781.816090919376;36140;36335.273294533436;37410.62589374071;37517;40424.79787601043;30890.382192356476;33813.85458575001;33971.320573588884;34202;34489.65510344865;26127.869360653076;26227.754449110635;26393.20820375392;26515.33711657224;26876.462470750866 15:59:07 INFO - PROCESS | 6349 | |2;jquery.html;353.33749220212104;356.59704533876715;362.10559917004997;363.27526584464;377.2982253009402;15982.680346393065;16954.745678814827;17190.57023574411;17409;17900.104994750243;16233.83766162338;16651.167441627902;17318.307267709275;17339.39312124076;17390.304387824504;5004.649674522785;5147.330846989905;5149.76826042829;5165.1219292720325;5167.173252279636;3775.9993601695437;3944.23087497938;5123.2827404163445;5258.079836028699;5927.673977931212;5889.2049573307695;5993.28080630325;6002.2197114375285;6004.369541198177;6007.939920600782;5814.389489103668;5957.821265362046;5962.8807423851495;5979.342272350038;6025.728842202089;5204;5314.627976041678;5318.414974352818;5335.466453354672;5357.919631205535;18173.7273940891;19987.300444484517;20782.792172078236;20814.895925520428;20832.27087051961;1757.6572568349177;1779.590694140355;1849.1678744564865;1980.9401969946155;2269.7389800173028;8991.910080899173;9488.810223795554;9531.237500999903;9615.278854085971;9742.610295588169;1641.1794102948527;1764.135573568955;1797.3170195325692;1813.936512222079;1824.771903512061;48055.51944480545;60940;64446.355536444506;66724;69365;9915.752106197258;10334.328268662513;10397.688069357931;10488.370697758006;10993.120550355952;14439.711205775826;14472.565823025327;14487.85512144865;14494.492692755703;14508.492202772852;6506;6746.763863264762;6897.206821215524;6937.410320122846;6950.687219075154;6179.196704428486;6209.348018458088;6289;6291.150694656163;6297.2128483939505;4371.650267978554;4458.8439404620685;4479;4497.865064048084;4619;1837.7702787151607;1842.5485755989869;1843.207420809065;1875.2217829600836;1878.5115869873932;847.9908908398985;864.5849992003873;898.1063841477641;934.5841100710119;962.7208109648124;16217.108059056629;17264.741028884324;17276.22256998438;18168.1824317906;18343.541411464554;881.2245224202661;883.8188171424842;899.4513346858411;931.5295775633398;967.5162418790604;9367.484788336707;9752.171065459373;9779.266555008402;9789.412635241908;10011.549480273405;400436.9803358856;419820;426346;426939;431735;77981.61009194917;78751.60624196957;80364;81165;81589.59205203936;923.5474617437542;940.849464085743;954.6897258391051;962.5139304651107;981.2787601113176;15310.770338445154;15407.922960385127;15526.689466210386;15621.21893905323;15744;35304.293914122085;35940.28119437649;36167;36293.637063630085;36999.26001480009;51126.74436627942;51381.48618513917;51614;51769.741151294;51803.48196517987;6557.9344206557325;6961.408280296029;7828.530288182727;8119.269265766109;8254.587270636564;10404.271700981162;11429.428528573371;11607.187496875138;11654.18420710576;11841.23032002917;9931.65239216624;9960.800783984425;10088.848667269851;10173.389596624227;10312;9761.414315141114;10106.343087699275;10192.796144076929;10221.131203847755;10279.897201028192;9817.607295708092;10181.898181018096;10377.481125943823;10399.58401663925;10642.095421889226;7157.355837974607;7166.319199676022;7189.460790440842;7217.891731624135;7358.816029599088;10121.898781012096;10129.189664826943;10143.7464063399;10180.083792458714;10324.587016519255 15:59:07 INFO - PROCESS | 6349 | |3;mootools.html;1271.1927925767136;1380.9102408343456;1429.0782445322768;1538.569200623825;1737.4005967941064;6762.729490820368;7656.655450504727;7882.487638303506;7891.131975482706;8078.798030049252;7815.179406162356;7996.560189189592;8006.319462845665;8012.759617211493;8014.679412823494;1836.917338719761;2099.9265025724103;2172.261431113417;2189.5073608438133;2201.8458707890454;2246.8764217968005;2263.1173842201556;2267.886605669718;2269.9773002270013;2272.4432514034083;2205.746339171;2215.3686199433164;2249.089118906845;2251.5609456156058;2252.785985331399;1821.1804687890503;2061.185831596526;2126.33020598511;2127.106615221603;2129.1909074551572;2139.7539282982502;2151.0965394534333;2153.946151346229;2158.978410215909;2161.373201771484;7737.381009519225;7827.1390147083775;7828.765137045898;7832.530048197126;7841.843163136706;4350.086481838817;4474.284114541658;4476.171908196994;4480.48474425442;4484.820607175741;2610.1777939948856;2796.4686709525326;2806.7754579633784;2810.718928107173;2822.252103192656;871.7210492642357;872.1757938747886;886.5655828643918;897.6274845938927;907.6959218661756;10860.67417977454;10906.945465272624;10942;10951.34291942486;10953.945230273797;4137.193247316804;4175.478065241845;4198.677416613757;4209.2423363794815;4231.069164783743;9353.158215760614;9355.766105847271;9357.672481463116;9367.437953722661;9375.10936461035;4467.218236808546;4486.327050942385;4486.528914463935;4489.349044388546;4489.88775280614;3439.638837922032;3870.787106709102;3962.5046869141356;3966.9801650991562;4012;3507.175813683782;3589.425691889285;3622.5109610202812;3646.7994260315936;3689.1883785567134;1148.4544841200363;1170.449888552379;1171.5899435197782;1172.7713095946208;1180.4392913366082;700.1178515071047;792.8572856885701;802.1336956087412;824.4929368438367;828.2959484438179;4962.9007419851405;4969.850904472872;4985.576226020757;5055.9494405055475;5092.949070509321;404.34092429340575;412.015283472501;426.2327809942092;427.55534244386115;428.39168380899196;3402.336544373873;3452.689257966795;3454.240067185215;3459.5848498179876;3466.2201004773724;9591.808163836684;9828.803423931624;9891.060349266809;10000.899991000142;10039.849402258971;9672.56473458696;9804.46075465842;9817.705468835946;9872.506374681238;9919.80160396788;6772.119624448888;7044.436445084483;7389.593572353572;7456.627168641655;7468.365188958891;5218.530332270114;5264.6314757966575;5270.6310558262085;5329.840104796862;5393.1370980642905;8623.698170564001;8834.514101724468;9055.18503334703;9102.180803727697;9104.180623743894;9097.272218222659;9165.312601555044;9165.587548560197;9178;9272.768180795534;5832.387599302182;6452.741890324334;6686.197656281277;6710;6853.074834897225;7775.650095745818;7790.5325680459355;7842;7856.214378562098;7859.882101768592;6636.2700102987665;6834.726610935507;6836.316368363124;6880.243173251039;7185.2814718527725;6665.166854143232;6692.364225398579;6719.664016799043;6768.2216545097945;6812.965935170292;6435.646039467874;6470.870582588416;6524.923387640986;6564.573302735498;6565.967170164118;4393.406890069799;4452.799624016991;4473.105378924163;4480.932786008148;4553.590176883964;4486.080353527454;4495.617872480876;4590.403247577793;4597.770111494345;4613.907721845611 15:59:07 INFO - PROCESS | 6349 | |4;prototype.html;290.69477049098447;293.8002158532197;297.34287225232237;300.55968006869944;301.94564978303896;4777.355057067295;4809.591184749296;4827.106985207734;4838.34682317887;4891.510848915107;4796.465131158031;4818.156822556056;4823.228283474645;4828.0585285869265;4860.538248866352;3642.2715456908527;3708.7033037357082;3760.4171353440124;3814.389697648377;3833.5208098987628;3786.185970016446;3813.9809300953457;3828.4257361395735;3834.6740527055235;3846.0769415340255;3798.9932667843045;3800.182960663457;3805.353089974711;3807.2956503047026;3814.9618503814886;3631.7820930744106;3696.31618150643;3789.3936970084656;3801.657850793414;3819.1788765415567;3392.423288041039;3392.423288041039;3392.5928888533285;3393.2025973896116;3401.9829900850336;4575.816967321303;4593.173228818811;4615.83844565442;4623.2371658676275;4637.281221410687;4295.656347492193;4353.651707863363;4370.650347972155;4383.3424986252;4390.968122491212;5038.647294689373;5064.873378165538;5065.138926382524;5066.138756411382;5067.9746601267125;3708.7589306694977;3710.424884142962;3715.665590096877;3717.4795528626014;3718.0998820919726;5094.6688465249645;5254.4220135785035;5255;5257.815976440845;5266.815661451831;3773.716971227169;3800.5439347278502;3802.6767724743154;3806.7437745543903;3811.5235595550557;3877.747946383476;3878.3600705883214;3879.797262848526;3887.436321733333;3887.941680874789;4310.8706738797255;4311.0300182458695;4318.395424640553;4336.4145840311785;4369.191699535596;4834.20235661112;5020.447750747415;5036.471170527115;5037.899242015139;5092.446803724825;1318.3671837518045;1321.431784332727;1327.2700014991715;1332.320516536554;1335.5125379236508;840.7099550655014;855.6962278391206;878.4070752242211;904.9728508144766;932.1563984593953;4322.956770432255;4324.416203812508;4325.091730736518;4334.934975975363;4347.56524347569;5016.172331565251;5037.647364684511;5051.545360917462;5071.2139618359215;5078.806480477085;4149.854755083557;4301.741895486281;4352.194843953878;4353.325234588644;4362.149380870763;2736.3979924416594;2756.2007017964565;2775.5836624506487;2779.152358530627;2779.777617790572;4836.637252206098;4848.5636292733825;4854.514548545188;4859.34398856157;4899.559039686445;5108.514691104339;5358.705271210043;5361.383440904346;5408.4050754416985;5408.945910540923;4931.161702510582;4937.21004639256;4950.2079667253065;4955.587657517663;4963.404391473046;5116.134964419936;5205.115130427761;5218.739063046908;5237.8428647140645;5244.951009797979;4880.5607495324175;5146.99633571465;5164.638475306692;5166.354205724285;5186.299849520266;4832.251001094836;4866.586340161126;4879.39007624047;4897.657163998629;4903.828366007173;3652.6164752700583;3680.7239457040296;3685.576158741779;3685.8709945152864;3692.3353796315846;5097.490250974873;5199.870003249949;5211.1922651989;5211.426743058185;5236.738163091906;5020.598352131894;5035.7985680572365;5040.873978150576;5062.670926389773;5080.796768129381;4997.600191984705;5028.522290382408;5046.318746969112;5068.189089745623;5090.287359769562;4700.989287303246;4936.358273424432;4958.950410495849;4969.950300497093;4981.950180498148;4842.934554398027;4963.51824087956;4982.402111746614;5040.672356296829;5052.216906379517;4945.961348116792;4947.1165135368565;4954.761309672582;4985.376827896513;4997.3253610762085 15:59:07 INFO - PROCESS | 6349 | |5;yui.html;695.381110811378;722.4292808681142;731.3852975612186;780.6565111351005;786.1588100732218;14169.574912752609;14622.48821291255;14685.70628587429;14692.191929443876;14702.926485367558;13067.215967041984;13136.21182729037;13170.604881853536;13200.53798117066;13204.933975330134;627.6296984778979;800.5556915911652;806.3388021822079;806.6208881825562;806.6773290683715;770.6108415250291;800.9959950200241;801.5992003998001;802.1377019703842;804.8752443371287;824.6412810427453;825.8265764189528;830.4975489828627;831.3058596072287;832.1179549677331;649.7985624456434;793.5238856685942;820.306840719591;821.4701517521195;822.8930239068912;599.547341756975;600.9969950150265;601.2844714789387;601.3835818286248;601.4677010845434;13200.14199077057;13201.27392993385;13232.073754837165;13245.801312980313;13261;11428.542858285658;11725.237859539102;11850.644480665595;11864.170205977984;11918.165728399015;11138.665840024812;11166.218364714474;11185;11312.773744525146;11353.489092990836;4092.897677558055;4117.1765646870745;4121.5878412158845;4139.5653456387245;4190.182914331706;48387;49131.50868491341;49742;49964.50035499599;50088;9936.900630993741;9989.600415983425;10011.24915631316;10078.445685487222;10251.641192558225;16335.428260010842;16700.331986720394;16743.246553905105;16773.496795096165;16831.495055148363;6810.897836532456;6848.315168483212;6899.827504312432;6989.091418115714;6995.335443132894;3875.418687196943;3878.3600705883778;3885.9028524286555;3894.532656081232;3918.5885482023978;2740.5478096114316;2819.971800282012;2827.434513097389;2830.3065748891654;2831.0232969625445;493.58292243054564;495.2175562611066;504.707269783532;507.3809951858726;509.1471784760511;533.7011273686747;536.0752701589804;541.1287826599172;548.1941545927481;566.5948846574637;7595.468317217742;7612.352949999201;7772.378209743207;7893.210678932061;7917.683292668229;546.4617351908367;577.9017567013618;588.0414923674468;590.1785936063951;591.914172444993;3520.471929210588;3619;3632.909177270589;3652.652997965189;3781.0358358618378;44335;46483.30275045877;47772.522274776806;47887;53158;44367;47827.28259076117;47836;48885.266720999214;49135.75432122888;44462.33306500405;45294.54705452969;45737.77131114323;45910.540894590624;46733.766331168124;8046.275835174863;8143.3485321172975;8175.305099066408;8333;8547.145285471403;28688.282792930342;29997.550036749904;30558.541621876135;31265.68734312628;31421.68578314279;38390.23219535538;38452.80773596114;40659.390109148975;41132.58867411288;41351.79324103481;10301.124404425707;10757.354558726502;10869.402182880018;10954.178436617127;11026.228164028442;11665.35840528779;11843.940780296043;12158.939205303917;12753.234805911676;12856.742865142833;10130.189584833342;10525.421101839165;10905.127589792955;10936.343819370863;11723;11141.331520108819;11202.159838012345;11411.54353825871;11792.941035295113;11799.76400472003;10245.48772561354;10329.793404132026;10926.617568385069;10936.945315273373;11629.941850290694;8624.310055195443;8898.4660920345;8944.776380590538;9136.31477639193;9167.633294668405;8855.3801233913;8857.114288571349;8890.155435233642;8960.372773906025;9154.084591540792 15:59:07 INFO - PROCESS | 6349 | __end_tp_report 15:59:07 INFO - PROCESS | 6349 | __start_cc_report 15:59:07 INFO - PROCESS | 6349 | _x_x_mozilla_cycle_collect,911 15:59:07 INFO - PROCESS | 6349 | __end_cc_report 15:59:07 INFO - PROCESS | 6349 | __startTimestamp1463785147960__endTimestamp 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | ------- Summary: start ------- 15:59:07 INFO - PROCESS | 6349 | Number of tests: 6 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | [#0] dojo.html Cycles:165 Average:4689.73 Median:4797.69 stddev:484.90 (10.1%) stddev-sans-first:460.16 15:59:07 INFO - PROCESS | 6349 | Values: 2684.4 2688.4 2725.8 2733.6 2760.4 4602.9 4652.0 4660.4 4745.7 4783.5 4662.0 4664.0 4664.1 4703.1 4710.7 4131.1 4340.8 4392.5 4419.3 4460.6 4371.0 4372.5 4399.4 4417.8 4465.6 4250.3 4306.4 4334.4 4373.5 4380.9 4348.8 4371.8 4373.9 4419.6 4420.2 4363.5 4388.7 4404.8 4454.5 4458.3 4541.0 4592.6 4626.9 4659.9 4666.6 5112.3 5130.2 5133.5 5136.8 5144.4 5148.7 5157.0 5162.6 5168.6 5174.3 4630.0 4690.1 4697.1 4739.7 4805.8 5166.5 5195.9 5198.5 5203.7 5249.7 4853.6 5037.9 5043.7 5046.5 5050.4 5032.7 5086.6 5097.4 5102.6 5117.4 5009.8 5043.7 5048.7 5051.6 5110.5 4850.7 4936.6 5050.2 5063.4 5065.5 4309.7 4330.2 4331.0 4369.5 4375.8 3780.9 3798.0 3804.0 3841.7 3849.4 4229.9 4273.4 4301.8 4314.2 4329.8 5138.7 5146.5 5159.3 5168.1 5220.8 4355.4 4363.1 4364.9 4402.6 4408.8 4436.7 4528.2 4573.2 4616.5 4626.2 4749.0 4775.7 4780.0 4816.5 4831.0 5107.0 5111.0 5114.4 5116.5 5116.8 4597.6 4686.6 4713.5 4723.3 4797.7 4987.3 4996.1 5004.1 5005.6 5039.4 5014.2 5059.2 5059.5 5064.1 5114.5 4852.0 4908.1 4926.2 4956.2 4976.9 4942.6 4984.5 4985.8 4986.8 5028.3 4695.0 4878.1 4886.3 4894.5 4937.6 4903.0 4947.7 4957.4 4960.4 4961.2 4864.3 4871.1 4874.8 4880.5 4922.9 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | [#1] ext.html Cycles:180 Average:49010.41 Median:391720.00 stddev:87018.58 (22.2%) stddev-sans-first:87186.73 15:59:07 INFO - PROCESS | 6349 | Values: 585.7 591.0 595.0 601.2 613.9 40107.2 41263.8 41362.8 45543.8 45729.5 41173.8 41791.8 42271.6 42317.6 42337.6 7686.6 7856.1 7864.3 7874.8 7875.6 7775.7 7866.3 7875.9 7877.9 7881.1 7823.6 7879.0 7901.2 7903.1 7903.7 7824.8 7830.8 7861.6 7884.8 7885.8 7571.0 7612.3 7628.2 7656.8 7659.8 38996.6 39351.8 39398.2 39406.8 39507.8 2879.8 2986.0 3296.3 3677.7 3755.6 28021.3 28280.4 30648.5 31463.7 32200.8 3466.0 3468.9 3484.1 3549.3 3609.3 272374.0 279422.0 279637.6 279935.0 280106.0 57866.4 59161.7 59237.0 59272.7 59327.4 52729.7 59837.7 61072.1 61196.0 67534.7 13241.3 13302.5 13335.6 13351.9 13352.6 8864.6 9828.7 9895.9 9918.4 9918.4 18852.5 18953.0 19543.4 19703.0 19814.9 7176.3 7244.2 7383.4 7390.8 7461.3 823.5 862.2 879.3 930.7 1021.9 27591.4 28989.6 29165.5 29410.6 29474.3 929.2 931.1 948.5 961.4 1068.3 15251.2 15517.8 15683.2 15725.4 15806.4 387544.0 390028.0 390164.0 390279.0 393161.0 52685.0 53067.5 53090.7 53097.5 53200.0 1088.1 1199.6 1206.6 1229.8 1245.3 28668.6 29791.6 29933.6 30136.8 30574.1 43321.6 43421.6 43936.1 43958.1 43968.3 311106.0 314007.0 314558.0 314641.0 315340.0 22736.9 24965.8 25504.6 25533.2 26946.3 41636.0 41815.8 42234.0 42296.8 42840.1 35266.8 36637.3 36787.0 37149.3 37481.0 36190.8 36350.8 36392.6 36533.5 36781.8 36140.0 36335.3 37410.6 37517.0 40424.8 30890.4 33813.9 33971.3 34202.0 34489.7 26127.9 26227.8 26393.2 26515.3 26876.5 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | [#2] jquery.html Cycles:180 Average:25058.67 Median:4488.43 stddev:69315.87 (1544.3%) stddev-sans-first:69485.50 15:59:07 INFO - PROCESS | 6349 | Values: 353.3 356.6 362.1 363.3 377.3 15982.7 16954.7 17190.6 17409.0 17900.1 16233.8 16651.2 17318.3 17339.4 17390.3 5004.6 5147.3 5149.8 5165.1 5167.2 3776.0 3944.2 5123.3 5258.1 5927.7 5889.2 5993.3 6002.2 6004.4 6007.9 5814.4 5957.8 5962.9 5979.3 6025.7 5204.0 5314.6 5318.4 5335.5 5357.9 18173.7 19987.3 20782.8 20814.9 20832.3 1757.7 1779.6 1849.2 1980.9 2269.7 8991.9 9488.8 9531.2 9615.3 9742.6 1641.2 1764.1 1797.3 1813.9 1824.8 48055.5 60940.0 64446.4 66724.0 69365.0 9915.8 10334.3 10397.7 10488.4 10993.1 14439.7 14472.6 14487.9 14494.5 14508.5 6506.0 6746.8 6897.2 6937.4 6950.7 6179.2 6209.3 6289.0 6291.2 6297.2 4371.7 4458.8 4479.0 4497.9 4619.0 1837.8 1842.5 1843.2 1875.2 1878.5 848.0 864.6 898.1 934.6 962.7 16217.1 17264.7 17276.2 18168.2 18343.5 881.2 883.8 899.5 931.5 967.5 9367.5 9752.2 9779.3 9789.4 10011.5 400437.0 419820.0 426346.0 426939.0 431735.0 77981.6 78751.6 80364.0 81165.0 81589.6 923.5 940.8 954.7 962.5 981.3 15310.8 15407.9 15526.7 15621.2 15744.0 35304.3 35940.3 36167.0 36293.6 36999.3 51126.7 51381.5 51614.0 51769.7 51803.5 6557.9 6961.4 7828.5 8119.3 8254.6 10404.3 11429.4 11607.2 11654.2 11841.2 9931.7 9960.8 10088.8 10173.4 10312.0 9761.4 10106.3 10192.8 10221.1 10279.9 9817.6 10181.9 10377.5 10399.6 10642.1 7157.4 7166.3 7189.5 7217.9 7358.8 10121.9 10129.2 10143.7 10180.1 10324.6 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | [#3] mootools.html Cycles:180 Average:5135.81 Median:4605.84 stddev:2977.22 (64.6%) stddev-sans-first:2971.41 15:59:07 INFO - PROCESS | 6349 | Values: 1271.2 1380.9 1429.1 1538.6 1737.4 6762.7 7656.7 7882.5 7891.1 8078.8 7815.2 7996.6 8006.3 8012.8 8014.7 1836.9 2099.9 2172.3 2189.5 2201.8 2246.9 2263.1 2267.9 2270.0 2272.4 2205.7 2215.4 2249.1 2251.6 2252.8 1821.2 2061.2 2126.3 2127.1 2129.2 2139.8 2151.1 2153.9 2159.0 2161.4 7737.4 7827.1 7828.8 7832.5 7841.8 4350.1 4474.3 4476.2 4480.5 4484.8 2610.2 2796.5 2806.8 2810.7 2822.3 871.7 872.2 886.6 897.6 907.7 10860.7 10906.9 10942.0 10951.3 10953.9 4137.2 4175.5 4198.7 4209.2 4231.1 9353.2 9355.8 9357.7 9367.4 9375.1 4467.2 4486.3 4486.5 4489.3 4489.9 3439.6 3870.8 3962.5 3967.0 4012.0 3507.2 3589.4 3622.5 3646.8 3689.2 1148.5 1170.4 1171.6 1172.8 1180.4 700.1 792.9 802.1 824.5 828.3 4962.9 4969.9 4985.6 5055.9 5092.9 404.3 412.0 426.2 427.6 428.4 3402.3 3452.7 3454.2 3459.6 3466.2 9591.8 9828.8 9891.1 10000.9 10039.8 9672.6 9804.5 9817.7 9872.5 9919.8 6772.1 7044.4 7389.6 7456.6 7468.4 5218.5 5264.6 5270.6 5329.8 5393.1 8623.7 8834.5 9055.2 9102.2 9104.2 9097.3 9165.3 9165.6 9178.0 9272.8 5832.4 6452.7 6686.2 6710.0 6853.1 7775.7 7790.5 7842.0 7856.2 7859.9 6636.3 6834.7 6836.3 6880.2 7185.3 6665.2 6692.4 6719.7 6768.2 6813.0 6435.6 6470.9 6524.9 6564.6 6566.0 4393.4 4452.8 4473.1 4480.9 4553.6 4486.1 4495.6 4590.4 4597.8 4613.9 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | [#4] prototype.html Cycles:180 Average:4201.64 Median:4825.17 stddev:1200.80 (24.9%) stddev-sans-first:1167.74 15:59:07 INFO - PROCESS | 6349 | Values: 290.7 293.8 297.3 300.6 301.9 4777.4 4809.6 4827.1 4838.3 4891.5 4796.5 4818.2 4823.2 4828.1 4860.5 3642.3 3708.7 3760.4 3814.4 3833.5 3786.2 3814.0 3828.4 3834.7 3846.1 3799.0 3800.2 3805.4 3807.3 3815.0 3631.8 3696.3 3789.4 3801.7 3819.2 3392.4 3392.4 3392.6 3393.2 3402.0 4575.8 4593.2 4615.8 4623.2 4637.3 4295.7 4353.7 4370.7 4383.3 4391.0 5038.6 5064.9 5065.1 5066.1 5068.0 3708.8 3710.4 3715.7 3717.5 3718.1 5094.7 5254.4 5255.0 5257.8 5266.8 3773.7 3800.5 3802.7 3806.7 3811.5 3877.7 3878.4 3879.8 3887.4 3887.9 4310.9 4311.0 4318.4 4336.4 4369.2 4834.2 5020.4 5036.5 5037.9 5092.4 1318.4 1321.4 1327.3 1332.3 1335.5 840.7 855.7 878.4 905.0 932.2 4323.0 4324.4 4325.1 4334.9 4347.6 5016.2 5037.6 5051.5 5071.2 5078.8 4149.9 4301.7 4352.2 4353.3 4362.1 2736.4 2756.2 2775.6 2779.2 2779.8 4836.6 4848.6 4854.5 4859.3 4899.6 5108.5 5358.7 5361.4 5408.4 5408.9 4931.2 4937.2 4950.2 4955.6 4963.4 5116.1 5205.1 5218.7 5237.8 5245.0 4880.6 5147.0 5164.6 5166.4 5186.3 4832.3 4866.6 4879.4 4897.7 4903.8 3652.6 3680.7 3685.6 3685.9 3692.3 5097.5 5199.9 5211.2 5211.4 5236.7 5020.6 5035.8 5040.9 5062.7 5080.8 4997.6 5028.5 5046.3 5068.2 5090.3 4701.0 4936.4 4959.0 4970.0 4982.0 4842.9 4963.5 4982.4 5040.7 5052.2 4946.0 4947.1 4954.8 4985.4 4997.3 15:59:07 INFO - PROCESS | 6349 | 15:59:07 INFO - PROCESS | 6349 | [#5] yui.html Cycles:180 Average:13035.76 Median:44878.44 stddev:14707.92 (32.8%) stddev-sans-first:14719.99 15:59:07 INFO - PROCESS | 6349 | Values: 695.4 722.4 731.4 780.7 786.2 14169.6 14622.5 14685.7 14692.2 14702.9 13067.2 13136.2 13170.6 13200.5 13204.9 627.6 800.6 806.3 806.6 806.7 770.6 801.0 801.6 802.1 804.9 824.6 825.8 830.5 831.3 832.1 649.8 793.5 820.3 821.5 822.9 599.5 601.0 601.3 601.4 601.5 13200.1 13201.3 13232.1 13245.8 13261.0 11428.5 11725.2 11850.6 11864.2 11918.2 11138.7 11166.2 11185.0 11312.8 11353.5 4092.9 4117.2 4121.6 4139.6 4190.2 48387.0 49131.5 49742.0 49964.5 50088.0 9936.9 9989.6 10011.2 10078.4 10251.6 16335.4 16700.3 16743.2 16773.5 16831.5 6810.9 6848.3 6899.8 6989.1 6995.3 3875.4 3878.4 3885.9 3894.5 3918.6 2740.5 2820.0 2827.4 2830.3 2831.0 493.6 495.2 504.7 507.4 509.1 533.7 536.1 541.1 548.2 566.6 7595.5 7612.4 7772.4 7893.2 7917.7 546.5 577.9 588.0 590.2 591.9 3520.5 3619.0 3632.9 3652.7 3781.0 44335.0 46483.3 47772.5 47887.0 53158.0 44367.0 47827.3 47836.0 48885.3 49135.8 44462.3 45294.5 45737.8 45910.5 46733.8 8046.3 8143.3 8175.3 8333.0 8547.1 28688.3 29997.6 30558.5 31265.7 31421.7 38390.2 38452.8 40659.4 41132.6 41351.8 10301.1 10757.4 10869.4 10954.2 11026.2 11665.4 11843.9 12158.9 12753.2 12856.7 10130.2 10525.4 10905.1 10936.3 11723.0 11141.3 11202.2 11411.5 11792.9 11799.8 10245.5 10329.8 10926.6 10936.9 11629.9 8624.3 8898.5 8944.8 9136.3 9167.6 8855.4 8857.1 8890.2 8960.4 9154.1 15:59:07 INFO - PROCESS | 6349 | -------- Summary: end -------- 15:59:07 INFO - PROCESS | 6349 | 15:59:08 INFO - TEST-INFO | 6349: exit 0 15:59:08 INFO - TEST-OK | dromaeo_css | took 1118766ms 15:59:08 INFO - TEST-START | kraken 15:59:08 INFO - operating with platform_type : linux_ 15:59:08 INFO - Initialising browser for kraken test... 15:59:08 INFO - TEST-INFO | started process 6527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp45ztrH/profile http://localhost:42289/getInfo.html) 15:59:12 INFO - PROCESS | 6527 | __metrics Screen width/height:1600/1200 15:59:12 INFO - PROCESS | 6527 | colorDepth:24 15:59:12 INFO - PROCESS | 6527 | Browser inner width/height: 1024/697 15:59:12 INFO - PROCESS | 6527 | __metrics 15:59:13 ERROR - PROCESS | 6527 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:59:14 INFO - TEST-INFO | 6527: exit 0 15:59:14 INFO - Browser initialized. 15:59:14 INFO - Running cycle 1/1 for kraken test... 15:59:14 INFO - TEST-INFO | started process 6608 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp45ztrH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:26 INFO - PROCESS | 6608 | RSS: Main: 153976832 15:59:26 INFO - PROCESS | 6608 | 15:59:29 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42289/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:30 INFO - PROCESS | 6608 | RSS: Main: 209207296 15:59:30 INFO - PROCESS | 6608 | 15:59:33 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42289/tests/kraken/driver.html?testName=audio-dft) 15:59:33 INFO - PROCESS | 6608 | RSS: Main: 197701632 15:59:33 INFO - PROCESS | 6608 | 15:59:37 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42289/tests/kraken/driver.html?testName=audio-fft) 15:59:37 INFO - PROCESS | 6608 | RSS: Main: 267640832 15:59:37 INFO - PROCESS | 6608 | 15:59:40 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42289/tests/kraken/driver.html?testName=audio-oscillator) 15:59:40 INFO - PROCESS | 6608 | RSS: Main: 176447488 15:59:40 INFO - PROCESS | 6608 | 15:59:43 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42289/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:43 INFO - PROCESS | 6608 | RSS: Main: 181276672 15:59:43 INFO - PROCESS | 6608 | 15:59:47 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42289/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:47 INFO - PROCESS | 6608 | RSS: Main: 216100864 15:59:47 INFO - PROCESS | 6608 | 15:59:51 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42289/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:52 INFO - PROCESS | 6608 | RSS: Main: 214544384 15:59:52 INFO - PROCESS | 6608 | 15:59:55 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42289/tests/kraken/driver.html?testName=json-parse-financial) 15:59:55 INFO - PROCESS | 6608 | RSS: Main: 215478272 15:59:55 INFO - PROCESS | 6608 | 15:59:57 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42289/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:58 INFO - PROCESS | 6608 | RSS: Main: 208879616 15:59:58 INFO - PROCESS | 6608 | 16:00:00 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:00 INFO - PROCESS | 6608 | RSS: Main: 210456576 16:00:00 INFO - PROCESS | 6608 | 16:00:03 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:03 INFO - PROCESS | 6608 | RSS: Main: 215371776 16:00:03 INFO - PROCESS | 6608 | 16:00:06 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:07 INFO - PROCESS | 6608 | RSS: Main: 219615232 16:00:07 INFO - PROCESS | 6608 | 16:00:10 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:10 INFO - PROCESS | 6608 | RSS: Main: 218292224 16:00:10 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | Cycle 1(1): loaded http://localhost:42289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42289/tests/kraken/driver.html?testName=ai-astar) 16:00:12 INFO - PROCESS | 6608 | RSS: Main: 264986624 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | __start_tp_report 16:00:12 INFO - PROCESS | 6608 | _x_x_mozilla_page_load 16:00:12 INFO - PROCESS | 6608 | _x_x_mozilla_page_load_details 16:00:12 INFO - PROCESS | 6608 | |i|pagename|runs| 16:00:12 INFO - PROCESS | 6608 | |0;ai-astar;114;109;114;114;113;115;112;114;113;113 16:00:12 INFO - PROCESS | 6608 | |1;audio-beat-detection;152;137;129;212;129;131;160;140;180;130 16:00:12 INFO - PROCESS | 6608 | |2;audio-dft;161;162;159;160;161;160;160;161;161;161 16:00:12 INFO - PROCESS | 6608 | |3;audio-fft;76;76;76;139;77;78;75;77;119;76 16:00:12 INFO - PROCESS | 6608 | |4;audio-oscillator;93;94;163;94;94;93;130;94;96;138 16:00:12 INFO - PROCESS | 6608 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;118;117 16:00:12 INFO - PROCESS | 6608 | |6;imaging-darkroom;146;147;146;147;146;146;146;147;146;146 16:00:12 INFO - PROCESS | 6608 | |7;imaging-desaturate;94;95;95;95;94;95;94;96;96;95 16:00:12 INFO - PROCESS | 6608 | |8;json-parse-financial;65;66;65;72;64;65;68;64;64;64 16:00:12 INFO - PROCESS | 6608 | |9;json-stringify-tinderbox;65;65;64;66;66;65;66;65;66;67 16:00:12 INFO - PROCESS | 6608 | |10;stanford-crypto-aes;82;83;82;81;86;84;82;88;159;82 16:00:12 INFO - PROCESS | 6608 | |11;stanford-crypto-ccm;132;134;143;129;137;129;127;136;158;132 16:00:12 INFO - PROCESS | 6608 | |12;stanford-crypto-pbkdf2;175;179;171;170;173;171;172;168;168;171 16:00:12 INFO - PROCESS | 6608 | |13;stanford-crypto-sha256-iterative;71;66;67;66;65;65;66;66;64;65 16:00:12 INFO - PROCESS | 6608 | __end_tp_report 16:00:12 INFO - PROCESS | 6608 | __start_cc_report 16:00:12 INFO - PROCESS | 6608 | _x_x_mozilla_cycle_collect,2122 16:00:12 INFO - PROCESS | 6608 | __end_cc_report 16:00:12 INFO - PROCESS | 6608 | __startTimestamp1463785212912__endTimestamp 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | ------- Summary: start ------- 16:00:12 INFO - PROCESS | 6608 | Number of tests: 14 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:1.66 (1.5%) stddev-sans-first:1.73 16:00:12 INFO - PROCESS | 6608 | Values: 114.0 109.0 114.0 114.0 113.0 115.0 112.0 114.0 113.0 113.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#1] audio-beat-detection Cycles:10 Average:150.00 Median:146.00 stddev:27.41 (18.8%) stddev-sans-first:29.06 16:00:12 INFO - PROCESS | 6608 | Values: 152.0 137.0 129.0 212.0 129.0 131.0 160.0 140.0 180.0 130.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 16:00:12 INFO - PROCESS | 6608 | Values: 161.0 162.0 159.0 160.0 161.0 160.0 160.0 161.0 161.0 161.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#3] audio-fft Cycles:10 Average:86.90 Median:76.00 stddev:22.70 (29.9%) stddev-sans-first:23.73 16:00:12 INFO - PROCESS | 6608 | Values: 76.0 76.0 76.0 139.0 77.0 78.0 75.0 77.0 119.0 76.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:94.00 stddev:25.34 (27.0%) stddev-sans-first:26.22 16:00:12 INFO - PROCESS | 6608 | Values: 93.0 94.0 163.0 94.0 94.0 93.0 130.0 94.0 96.0 138.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:00:12 INFO - PROCESS | 6608 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:00:12 INFO - PROCESS | 6608 | Values: 146.0 147.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 16:00:12 INFO - PROCESS | 6608 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 94.0 96.0 96.0 95.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:65.00 stddev:2.54 (3.9%) stddev-sans-first:2.68 16:00:12 INFO - PROCESS | 6608 | Values: 65.0 66.0 65.0 72.0 64.0 65.0 68.0 64.0 64.0 64.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 16:00:12 INFO - PROCESS | 6608 | Values: 65.0 65.0 64.0 66.0 66.0 65.0 66.0 65.0 66.0 67.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:82.50 stddev:24.03 (29.1%) stddev-sans-first:25.27 16:00:12 INFO - PROCESS | 6608 | Values: 82.0 83.0 82.0 81.0 86.0 84.0 82.0 88.0 159.0 82.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:135.00 stddev:9.12 (6.8%) stddev-sans-first:9.57 16:00:12 INFO - PROCESS | 6608 | Values: 132.0 134.0 143.0 129.0 137.0 129.0 127.0 136.0 158.0 132.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:171.50 stddev:3.29 (1.9%) stddev-sans-first:3.28 16:00:12 INFO - PROCESS | 6608 | Values: 175.0 179.0 171.0 170.0 173.0 171.0 172.0 168.0 168.0 171.0 16:00:12 INFO - PROCESS | 6608 | 16:00:12 INFO - PROCESS | 6608 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.91 (2.9%) stddev-sans-first:0.88 16:00:12 INFO - PROCESS | 6608 | Values: 71.0 66.0 67.0 66.0 65.0 65.0 66.0 66.0 64.0 65.0 16:00:12 INFO - PROCESS | 6608 | -------- Summary: end -------- 16:00:12 INFO - PROCESS | 6608 | 16:00:13 INFO - TEST-INFO | 6608: exit 0 16:00:13 INFO - TEST-OK | kraken | took 65190ms 16:00:13 INFO - SUITE-END | took 1183s 16:00:14 INFO - Completed test suite (00:19:44) 16:00:14 INFO - javascript benchmark 16:00:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2684.382592003839, 2688.408550118973, 2725.8228215166014, 2733.630959820425, 2760.406512599791, 4602.930956035654, 4652.0, 4660.440747110347, 4745.715257084573, 4783.5216478352195, 4661.953380466194, 4664.0205556833025, 4664.090502352043, 4703.05938812238, 4710.670253082286, 4131.132462182945, 4340.761258130801, 4392.516823149467, 4419.315006174048, 4460.598546130847, 4371.0, 4372.519022907493, 4399.38408622793, 4417.823287068513, 4465.598096171344, 4250.341197114452, 4306.397104405386, 4334.414853994702, 4373.453318335208, 4380.912381752347, 4348.782560871975, 4371.825126994916, 4373.912521749579, 4419.558044195587, 4420.248557745191, 4363.498197707241, 4388.71473354231, 4404.788683112138, 4454.510003899601, 4458.264386376244, 4540.954590454086, 4592.586667199935, 4626.8895465088135, 4659.906801863944, 4666.626669866402, 5112.335396398445, 5130.20481825314, 5133.460986596391, 5136.845894623168, 5144.4083930348115, 5148.6910785353, 5157.045946499909, 5162.638615296931, 5168.586513078944, 5174.301469301671, 4629.976850115761, 4690.085433340534, 4697.131030759252, 4739.739314337676, 4805.8077676892535, 5166.509181627739, 5195.948040519622, 5198.4801519847715, 5203.661761985459, 5249.685018898802, 4853.611711063107, 5037.924431133536, 5043.672161309503, 5046.4953504649975, 5050.3939527256225, 5032.72320022395, 5086.64393492452, 5097.439281679012, 5102.566281866083, 5117.437081920985, 5009.849704508871, 5043.672161309503, 5048.697078175322, 5051.595872330205, 5110.540051395318, 4850.708957462563, 4936.555709986119, 5050.191969284897, 5063.417706963673, 5065.518775716301, 4309.741415515079, 4330.17726631939, 4331.025519258142, 4369.453818272716, 4375.824967001348, 3780.905477363088, 3798.031501967036, 3803.980980095137, 3841.692664586826, 3849.4418309344987, 4229.851955181615, 4273.380359847804, 4301.78491075445, 4314.245007123804, 4329.848455304113, 5138.717370544656, 5146.536811686891, 5159.303494028333, 5168.095583272912, 5220.817271395483, 4355.412019377407, 4363.127374525107, 4364.912701745948, 4402.581754733295, 4408.823647054146, 4436.689431739747, 4528.230200865861, 4573.245414506636, 4616.515265897099, 4626.167289887852, 4749.026449577897, 4775.665703400868, 4780.043991201843, 4816.470188279286, 4831.0096430232425, 5107.0, 5110.97444512775, 5114.360704911886, 5116.462771408948, 5116.795328186831, 4597.632189424771, 4686.554777296152, 4713.528647135397, 4723.291506274087, 4797.688150270221, 4987.326710894128, 4996.075725990703, 5004.1492946198505, 5005.649604527794, 5039.420466646383, 5014.197728363444, 5059.215821547666, 5059.519345662156, 5064.063148317573, 5114.4629813869005, 4852.029594081268, 4908.141075311878, 4926.236433352836, 4956.207006878882, 4976.925346119883, 4942.55517003457, 4984.501549844987, 4985.800567977385, 4986.775595098156, 5028.321176641253, 4694.953050469451, 4878.121938051043, 4886.291487734187, 4894.510548945077, 4937.604991600591, 4902.950970490392, 4947.678400903929, 4957.380327459067, 4960.379952505937, 4961.181405068123, 4864.270359446112, 4871.147549178951, 4874.756262186947, 4880.536349046835, 4922.8769280768265], "value": 4659.7975050039095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.7100735136107, 590.9658098327926, 594.9791757288493, 601.1703848688808, 613.8526753579142, 40107.19785604286, 41263.79368103159, 41362.793186034105, 45543.77228113855, 45729.542704572945, 41173.794131029375, 41791.79104104475, 42271.57728422715, 42317.57682423175, 42337.57662423375, 7686.615669216545, 7856.1358250592675, 7864.331531819774, 7874.763757087268, 7875.566843823616, 7775.650095745705, 7866.292033716964, 7875.921240787576, 7877.881831772528, 7881.093674227452, 7823.608819559027, 7878.960605196966, 7901.170377110407, 7903.0911445183965, 7903.683852645916, 7824.843503129934, 7830.765077047698, 7861.606919654052, 7884.842303153962, 7885.802854928644, 7571.0, 7612.314891659722, 7628.199039100926, 7656.770296891046, 7659.770206893746, 38996.61003389958, 39351.8032409839, 39398.21203575913, 39406.802965984985, 39507.802460987514, 2879.8128121672025, 2986.044465770954, 3296.2912973710527, 3677.6506231907924, 3755.605661405568, 28021.299467513476, 28280.434391312265, 30648.540271895938, 31463.685363146305, 32200.838995805105, 3465.9826700866333, 3468.8612455501748, 3484.0914412103098, 3549.3256281306726, 3609.314230296235, 272374.0, 279422.0, 279637.60181199166, 279935.0, 280106.0, 57866.4213357861, 59161.70419147877, 59237.0, 59272.70363648154, 59327.406725933055, 52729.736351317995, 59837.70081149566, 61072.08391874126, 61196.0, 67534.66232668805, 13241.337933103307, 13302.534411295752, 13335.599932001862, 13351.866481335257, 13352.599422017354, 8864.64541418349, 9828.705138845846, 9895.901040989498, 9918.355306905029, 9918.355306905029, 18852.454918585816, 18953.0, 19543.413697589094, 19703.0, 19814.900925495567, 7176.282371762782, 7244.166920804069, 7383.375657355373, 7390.778276651709, 7461.328480436787, 823.4524041512327, 862.2153840005565, 879.2833840420037, 930.6696122876417, 1021.9116640777548, 27591.448171036467, 28989.56515652267, 29165.52531314582, 29410.558841617392, 29474.263143421587, 929.1776777551916, 931.064280398209, 948.5257371314343, 961.403929563675, 1068.3376306689902, 15251.161186134868, 15517.767233491506, 15683.215839207995, 15725.370985160465, 15806.36774529029, 387544.0, 390028.0, 390164.0, 390279.0, 393161.0, 52685.0, 53067.46932530702, 53090.73454632702, 53097.469025309256, 53200.0, 1088.0914436445498, 1199.6401079676236, 1206.6380085974172, 1229.7909355409602, 1245.250423155468, 28668.569971450026, 29791.55312670355, 29933.55099673463, 30136.84931575328, 30574.08277751671, 43321.56678433175, 43421.565784343016, 43936.12127757491, 43958.12083758371, 43968.34047489354, 311106.0, 314007.0, 314558.0, 314641.0, 315340.0, 22736.886315568318, 24965.750342496343, 25504.617430738926, 25533.23400297994, 26946.32634184161, 41636.0, 41815.7909210452, 42234.0, 42296.788516057226, 42840.14319713651, 35266.82366588253, 36637.26725465422, 36787.0, 37149.25701486009, 37481.0, 36190.8190459046, 36350.81824590966, 36392.636073638925, 36533.45199821952, 36781.816090919376, 36140.0, 36335.273294533436, 37410.62589374071, 37517.0, 40424.79787601043, 30890.382192356476, 33813.85458575001, 33971.320573588884, 34202.0, 34489.65510344865, 26127.869360653076, 26227.754449110635, 26393.20820375392, 26515.33711657224, 26876.462470750866], "value": 17977.049797541353, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.33749220212104, 356.59704533876715, 362.10559917004997, 363.27526584464, 377.2982253009402, 15982.680346393065, 16954.745678814827, 17190.57023574411, 17409.0, 17900.104994750243, 16233.83766162338, 16651.167441627902, 17318.307267709275, 17339.39312124076, 17390.304387824504, 5004.649674522785, 5147.330846989905, 5149.76826042829, 5165.1219292720325, 5167.173252279636, 3775.9993601695437, 3944.23087497938, 5123.2827404163445, 5258.079836028699, 5927.673977931212, 5889.2049573307695, 5993.28080630325, 6002.2197114375285, 6004.369541198177, 6007.939920600782, 5814.389489103668, 5957.821265362046, 5962.8807423851495, 5979.342272350038, 6025.728842202089, 5204.0, 5314.627976041678, 5318.414974352818, 5335.466453354672, 5357.919631205535, 18173.7273940891, 19987.300444484517, 20782.792172078236, 20814.895925520428, 20832.27087051961, 1757.6572568349177, 1779.590694140355, 1849.1678744564865, 1980.9401969946155, 2269.7389800173028, 8991.910080899173, 9488.810223795554, 9531.237500999903, 9615.278854085971, 9742.610295588169, 1641.1794102948527, 1764.135573568955, 1797.3170195325692, 1813.936512222079, 1824.771903512061, 48055.51944480545, 60940.0, 64446.355536444506, 66724.0, 69365.0, 9915.752106197258, 10334.328268662513, 10397.688069357931, 10488.370697758006, 10993.120550355952, 14439.711205775826, 14472.565823025327, 14487.85512144865, 14494.492692755703, 14508.492202772852, 6506.0, 6746.763863264762, 6897.206821215524, 6937.410320122846, 6950.687219075154, 6179.196704428486, 6209.348018458088, 6289.0, 6291.150694656163, 6297.2128483939505, 4371.650267978554, 4458.8439404620685, 4479.0, 4497.865064048084, 4619.0, 1837.7702787151607, 1842.5485755989869, 1843.207420809065, 1875.2217829600836, 1878.5115869873932, 847.9908908398985, 864.5849992003873, 898.1063841477641, 934.5841100710119, 962.7208109648124, 16217.108059056629, 17264.741028884324, 17276.22256998438, 18168.1824317906, 18343.541411464554, 881.2245224202661, 883.8188171424842, 899.4513346858411, 931.5295775633398, 967.5162418790604, 9367.484788336707, 9752.171065459373, 9779.266555008402, 9789.412635241908, 10011.549480273405, 400436.9803358856, 419820.0, 426346.0, 426939.0, 431735.0, 77981.61009194917, 78751.60624196957, 80364.0, 81165.0, 81589.59205203936, 923.5474617437542, 940.849464085743, 954.6897258391051, 962.5139304651107, 981.2787601113176, 15310.770338445154, 15407.922960385127, 15526.689466210386, 15621.21893905323, 15744.0, 35304.293914122085, 35940.28119437649, 36167.0, 36293.637063630085, 36999.26001480009, 51126.74436627942, 51381.48618513917, 51614.0, 51769.741151294, 51803.48196517987, 6557.9344206557325, 6961.408280296029, 7828.530288182727, 8119.269265766109, 8254.587270636564, 10404.271700981162, 11429.428528573371, 11607.187496875138, 11654.18420710576, 11841.23032002917, 9931.65239216624, 9960.800783984425, 10088.848667269851, 10173.389596624227, 10312.0, 9761.414315141114, 10106.343087699275, 10192.796144076929, 10221.131203847755, 10279.897201028192, 9817.607295708092, 10181.898181018096, 10377.481125943823, 10399.58401663925, 10642.095421889226, 7157.355837974607, 7166.319199676022, 7189.460790440842, 7217.891731624135, 7358.816029599088, 10121.898781012096, 10129.189664826943, 10143.7464063399, 10180.083792458714, 10324.587016519255], "value": 8232.49652814013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.1927925767136, 1380.9102408343456, 1429.0782445322768, 1538.569200623825, 1737.4005967941064, 6762.729490820368, 7656.655450504727, 7882.487638303506, 7891.131975482706, 8078.798030049252, 7815.179406162356, 7996.560189189592, 8006.319462845665, 8012.759617211493, 8014.679412823494, 1836.917338719761, 2099.9265025724103, 2172.261431113417, 2189.5073608438133, 2201.8458707890454, 2246.8764217968005, 2263.1173842201556, 2267.886605669718, 2269.9773002270013, 2272.4432514034083, 2205.746339171, 2215.3686199433164, 2249.089118906845, 2251.5609456156058, 2252.785985331399, 1821.1804687890503, 2061.185831596526, 2126.33020598511, 2127.106615221603, 2129.1909074551572, 2139.7539282982502, 2151.0965394534333, 2153.946151346229, 2158.978410215909, 2161.373201771484, 7737.381009519225, 7827.1390147083775, 7828.765137045898, 7832.530048197126, 7841.843163136706, 4350.086481838817, 4474.284114541658, 4476.171908196994, 4480.48474425442, 4484.820607175741, 2610.1777939948856, 2796.4686709525326, 2806.7754579633784, 2810.718928107173, 2822.252103192656, 871.7210492642357, 872.1757938747886, 886.5655828643918, 897.6274845938927, 907.6959218661756, 10860.67417977454, 10906.945465272624, 10942.0, 10951.34291942486, 10953.945230273797, 4137.193247316804, 4175.478065241845, 4198.677416613757, 4209.2423363794815, 4231.069164783743, 9353.158215760614, 9355.766105847271, 9357.672481463116, 9367.437953722661, 9375.10936461035, 4467.218236808546, 4486.327050942385, 4486.528914463935, 4489.349044388546, 4489.88775280614, 3439.638837922032, 3870.787106709102, 3962.5046869141356, 3966.9801650991562, 4012.0, 3507.175813683782, 3589.425691889285, 3622.5109610202812, 3646.7994260315936, 3689.1883785567134, 1148.4544841200363, 1170.449888552379, 1171.5899435197782, 1172.7713095946208, 1180.4392913366082, 700.1178515071047, 792.8572856885701, 802.1336956087412, 824.4929368438367, 828.2959484438179, 4962.9007419851405, 4969.850904472872, 4985.576226020757, 5055.9494405055475, 5092.949070509321, 404.34092429340575, 412.015283472501, 426.2327809942092, 427.55534244386115, 428.39168380899196, 3402.336544373873, 3452.689257966795, 3454.240067185215, 3459.5848498179876, 3466.2201004773724, 9591.808163836684, 9828.803423931624, 9891.060349266809, 10000.899991000142, 10039.849402258971, 9672.56473458696, 9804.46075465842, 9817.705468835946, 9872.506374681238, 9919.80160396788, 6772.119624448888, 7044.436445084483, 7389.593572353572, 7456.627168641655, 7468.365188958891, 5218.530332270114, 5264.6314757966575, 5270.6310558262085, 5329.840104796862, 5393.1370980642905, 8623.698170564001, 8834.514101724468, 9055.18503334703, 9102.180803727697, 9104.180623743894, 9097.272218222659, 9165.312601555044, 9165.587548560197, 9178.0, 9272.768180795534, 5832.387599302182, 6452.741890324334, 6686.197656281277, 6710.0, 6853.074834897225, 7775.650095745818, 7790.5325680459355, 7842.0, 7856.214378562098, 7859.882101768592, 6636.2700102987665, 6834.726610935507, 6836.316368363124, 6880.243173251039, 7185.2814718527725, 6665.166854143232, 6692.364225398579, 6719.664016799043, 6768.2216545097945, 6812.965935170292, 6435.646039467874, 6470.870582588416, 6524.923387640986, 6564.573302735498, 6565.967170164118, 4393.406890069799, 4452.799624016991, 4473.105378924163, 4480.932786008148, 4553.590176883964, 4486.080353527454, 4495.617872480876, 4590.403247577793, 4597.770111494345, 4613.907721845611], "value": 4029.568658188192, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.69477049098447, 293.8002158532197, 297.34287225232237, 300.55968006869944, 301.94564978303896, 4777.355057067295, 4809.591184749296, 4827.106985207734, 4838.34682317887, 4891.510848915107, 4796.465131158031, 4818.156822556056, 4823.228283474645, 4828.0585285869265, 4860.538248866352, 3642.2715456908527, 3708.7033037357082, 3760.4171353440124, 3814.389697648377, 3833.5208098987628, 3786.185970016446, 3813.9809300953457, 3828.4257361395735, 3834.6740527055235, 3846.0769415340255, 3798.9932667843045, 3800.182960663457, 3805.353089974711, 3807.2956503047026, 3814.9618503814886, 3631.7820930744106, 3696.31618150643, 3789.3936970084656, 3801.657850793414, 3819.1788765415567, 3392.423288041039, 3392.423288041039, 3392.5928888533285, 3393.2025973896116, 3401.9829900850336, 4575.816967321303, 4593.173228818811, 4615.83844565442, 4623.2371658676275, 4637.281221410687, 4295.656347492193, 4353.651707863363, 4370.650347972155, 4383.3424986252, 4390.968122491212, 5038.647294689373, 5064.873378165538, 5065.138926382524, 5066.138756411382, 5067.9746601267125, 3708.7589306694977, 3710.424884142962, 3715.665590096877, 3717.4795528626014, 3718.0998820919726, 5094.6688465249645, 5254.4220135785035, 5255.0, 5257.815976440845, 5266.815661451831, 3773.716971227169, 3800.5439347278502, 3802.6767724743154, 3806.7437745543903, 3811.5235595550557, 3877.747946383476, 3878.3600705883214, 3879.797262848526, 3887.436321733333, 3887.941680874789, 4310.8706738797255, 4311.0300182458695, 4318.395424640553, 4336.4145840311785, 4369.191699535596, 4834.20235661112, 5020.447750747415, 5036.471170527115, 5037.899242015139, 5092.446803724825, 1318.3671837518045, 1321.431784332727, 1327.2700014991715, 1332.320516536554, 1335.5125379236508, 840.7099550655014, 855.6962278391206, 878.4070752242211, 904.9728508144766, 932.1563984593953, 4322.956770432255, 4324.416203812508, 4325.091730736518, 4334.934975975363, 4347.56524347569, 5016.172331565251, 5037.647364684511, 5051.545360917462, 5071.2139618359215, 5078.806480477085, 4149.854755083557, 4301.741895486281, 4352.194843953878, 4353.325234588644, 4362.149380870763, 2736.3979924416594, 2756.2007017964565, 2775.5836624506487, 2779.152358530627, 2779.777617790572, 4836.637252206098, 4848.5636292733825, 4854.514548545188, 4859.34398856157, 4899.559039686445, 5108.514691104339, 5358.705271210043, 5361.383440904346, 5408.4050754416985, 5408.945910540923, 4931.161702510582, 4937.21004639256, 4950.2079667253065, 4955.587657517663, 4963.404391473046, 5116.134964419936, 5205.115130427761, 5218.739063046908, 5237.8428647140645, 5244.951009797979, 4880.5607495324175, 5146.99633571465, 5164.638475306692, 5166.354205724285, 5186.299849520266, 4832.251001094836, 4866.586340161126, 4879.39007624047, 4897.657163998629, 4903.828366007173, 3652.6164752700583, 3680.7239457040296, 3685.576158741779, 3685.8709945152864, 3692.3353796315846, 5097.490250974873, 5199.870003249949, 5211.1922651989, 5211.426743058185, 5236.738163091906, 5020.598352131894, 5035.7985680572365, 5040.873978150576, 5062.670926389773, 5080.796768129381, 4997.600191984705, 5028.522290382408, 5046.318746969112, 5068.189089745623, 5090.287359769562, 4700.989287303246, 4936.358273424432, 4958.950410495849, 4969.950300497093, 4981.950180498148, 4842.934554398027, 4963.51824087956, 4982.402111746614, 5040.672356296829, 5052.216906379517, 4945.961348116792, 4947.1165135368565, 4954.761309672582, 4985.376827896513, 4997.3253610762085], "value": 3820.957814236535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.381110811378, 722.4292808681142, 731.3852975612186, 780.6565111351005, 786.1588100732218, 14169.574912752609, 14622.48821291255, 14685.70628587429, 14692.191929443876, 14702.926485367558, 13067.215967041984, 13136.21182729037, 13170.604881853536, 13200.53798117066, 13204.933975330134, 627.6296984778979, 800.5556915911652, 806.3388021822079, 806.6208881825562, 806.6773290683715, 770.6108415250291, 800.9959950200241, 801.5992003998001, 802.1377019703842, 804.8752443371287, 824.6412810427453, 825.8265764189528, 830.4975489828627, 831.3058596072287, 832.1179549677331, 649.7985624456434, 793.5238856685942, 820.306840719591, 821.4701517521195, 822.8930239068912, 599.547341756975, 600.9969950150265, 601.2844714789387, 601.3835818286248, 601.4677010845434, 13200.14199077057, 13201.27392993385, 13232.073754837165, 13245.801312980313, 13261.0, 11428.542858285658, 11725.237859539102, 11850.644480665595, 11864.170205977984, 11918.165728399015, 11138.665840024812, 11166.218364714474, 11185.0, 11312.773744525146, 11353.489092990836, 4092.897677558055, 4117.1765646870745, 4121.5878412158845, 4139.5653456387245, 4190.182914331706, 48387.0, 49131.50868491341, 49742.0, 49964.50035499599, 50088.0, 9936.900630993741, 9989.600415983425, 10011.24915631316, 10078.445685487222, 10251.641192558225, 16335.428260010842, 16700.331986720394, 16743.246553905105, 16773.496795096165, 16831.495055148363, 6810.897836532456, 6848.315168483212, 6899.827504312432, 6989.091418115714, 6995.335443132894, 3875.418687196943, 3878.3600705883778, 3885.9028524286555, 3894.532656081232, 3918.5885482023978, 2740.5478096114316, 2819.971800282012, 2827.434513097389, 2830.3065748891654, 2831.0232969625445, 493.58292243054564, 495.2175562611066, 504.707269783532, 507.3809951858726, 509.1471784760511, 533.7011273686747, 536.0752701589804, 541.1287826599172, 548.1941545927481, 566.5948846574637, 7595.468317217742, 7612.352949999201, 7772.378209743207, 7893.210678932061, 7917.683292668229, 546.4617351908367, 577.9017567013618, 588.0414923674468, 590.1785936063951, 591.914172444993, 3520.471929210588, 3619.0, 3632.909177270589, 3652.652997965189, 3781.0358358618378, 44335.0, 46483.30275045877, 47772.522274776806, 47887.0, 53158.0, 44367.0, 47827.28259076117, 47836.0, 48885.266720999214, 49135.75432122888, 44462.33306500405, 45294.54705452969, 45737.77131114323, 45910.540894590624, 46733.766331168124, 8046.275835174863, 8143.3485321172975, 8175.305099066408, 8333.0, 8547.145285471403, 28688.282792930342, 29997.550036749904, 30558.541621876135, 31265.68734312628, 31421.68578314279, 38390.23219535538, 38452.80773596114, 40659.390109148975, 41132.58867411288, 41351.79324103481, 10301.124404425707, 10757.354558726502, 10869.402182880018, 10954.178436617127, 11026.228164028442, 11665.35840528779, 11843.940780296043, 12158.939205303917, 12753.234805911676, 12856.742865142833, 10130.189584833342, 10525.421101839165, 10905.127589792955, 10936.343819370863, 11723.0, 11141.331520108819, 11202.159838012345, 11411.54353825871, 11792.941035295113, 11799.76400472003, 10245.48772561354, 10329.793404132026, 10926.617568385069, 10936.945315273373, 11629.941850290694, 8624.310055195443, 8898.4660920345, 8944.776380590538, 9136.31477639193, 9167.633294668405, 8855.3801233913, 8857.114288571349, 8890.155435233642, 8960.372773906025, 9154.084591540792], "value": 6000.13957316054, "unit": "score"}], "name": "dromaeo_css", "value": 6319.898891653322, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 114.0, 114.0, 113.0, 115.0, 112.0, 114.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 137.0, 129.0, 212.0, 129.0, 131.0, 160.0, 140.0, 180.0, 130.0], "value": 150.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 159.0, 160.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 139.0, 77.0, 78.0, 75.0, 77.0, 119.0, 76.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 163.0, 94.0, 94.0, 93.0, 130.0, 94.0, 96.0, 138.0], "value": 108.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 96.0, 96.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 72.0, 64.0, 65.0, 68.0, 64.0, 64.0, 64.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 67.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 81.0, 86.0, 84.0, 82.0, 88.0, 159.0, 82.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 134.0, 143.0, 129.0, 137.0, 129.0, 127.0, 136.0, 158.0, 132.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 171.0, 170.0, 173.0, 171.0, 172.0, 168.0, 168.0, 171.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 67.0, 66.0, 65.0, 65.0, 66.0, 66.0, 64.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1573.5, "alertThreshold": 2.0}]} 16:00:14 INFO - Return code: 0 16:00:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:00:14 ERROR - # TBPL SUCCESS # 16:00:14 INFO - Running post-action listener: _resource_record_post_action 16:00:14 INFO - ##### 16:00:14 INFO - ##### Finished run-tests step (success) 16:00:14 INFO - ##### 16:00:14 INFO - Running post-run listener: _resource_record_post_run 16:00:14 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 606208; Write bytes: 386060288; Read time: 180; Write time: 211644 16:00:14 INFO - TinderboxPrint: CPU usage
13.0% 16:00:14 INFO - TinderboxPrint: I/O read bytes / time
606,208 / 180 16:00:14 INFO - TinderboxPrint: I/O write bytes / time
386,060,288 / 211,644 16:00:14 INFO - TinderboxPrint: CPU idle
8,334.2 (86.9%) 16:00:14 INFO - TinderboxPrint: CPU user
1,210.5 (12.6%) 16:00:14 INFO - TinderboxPrint: Swap in / out
0 / 0 16:00:14 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 110592; Write bytes: 96411648; Read time: 128; Write time: 44848 16:00:14 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 495616; Write bytes: 289648640; Read time: 52; Write time: 166796 16:00:14 INFO - Running post-run listener: _upload_blobber_files 16:00:14 INFO - Blob upload gear active. 16:00:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:14 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 16:00:14 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:14 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:15 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:00:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:00:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:16 INFO - (blobuploader) - INFO - Done attempting. 16:00:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:20 INFO - (blobuploader) - INFO - Done attempting. 16:00:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:00:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:00:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:21 INFO - (blobuploader) - INFO - Done attempting. 16:00:21 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:21 INFO - Return code: 0 16:00:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14230192da725f698386b0f696bd4015a8088587209e249c523f59717749968d8fda635ab1190ce37c5a89d1c3142a3994d86c269941023bac93e89ca4f8ee4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/763bb5c906e46ab6222ac88d0c31ff56b2be399da05dafc8ed6ee9070aabccb7e9b1b998bd77b1468be44a2dfa3415df3fe514da7c3294d4a903d65f5af208a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b86c1747ab2d75983255636c610a63ec6f692323cb07664082cc2d9d35c316ff1fdf0f155c024ed44f6219e203068db09e4418bcd43a860ba1033ecf6836463"} 16:00:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:21 INFO - Contents: 16:00:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14230192da725f698386b0f696bd4015a8088587209e249c523f59717749968d8fda635ab1190ce37c5a89d1c3142a3994d86c269941023bac93e89ca4f8ee4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/763bb5c906e46ab6222ac88d0c31ff56b2be399da05dafc8ed6ee9070aabccb7e9b1b998bd77b1468be44a2dfa3415df3fe514da7c3294d4a903d65f5af208a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b86c1747ab2d75983255636c610a63ec6f692323cb07664082cc2d9d35c316ff1fdf0f155c024ed44f6219e203068db09e4418bcd43a860ba1033ecf6836463"} 16:00:21 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:21 INFO - Copying logs to upload dir... 16:00:21 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1562.576644 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 26 mins, 2 secs) (at 2016-05-20 16:00:21.159393) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 16:00:21.162868) ========= 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=574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14230192da725f698386b0f696bd4015a8088587209e249c523f59717749968d8fda635ab1190ce37c5a89d1c3142a3994d86c269941023bac93e89ca4f8ee4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/763bb5c906e46ab6222ac88d0c31ff56b2be399da05dafc8ed6ee9070aabccb7e9b1b998bd77b1468be44a2dfa3415df3fe514da7c3294d4a903d65f5af208a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b86c1747ab2d75983255636c610a63ec6f692323cb07664082cc2d9d35c316ff1fdf0f155c024ed44f6219e203068db09e4418bcd43a860ba1033ecf6836463"} build_url:https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/CmsNtxq1REaRrDf8IZFQvg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14230192da725f698386b0f696bd4015a8088587209e249c523f59717749968d8fda635ab1190ce37c5a89d1c3142a3994d86c269941023bac93e89ca4f8ee4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/763bb5c906e46ab6222ac88d0c31ff56b2be399da05dafc8ed6ee9070aabccb7e9b1b998bd77b1468be44a2dfa3415df3fe514da7c3294d4a903d65f5af208a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b86c1747ab2d75983255636c610a63ec6f692323cb07664082cc2d9d35c316ff1fdf0f155c024ed44f6219e203068db09e4418bcd43a860ba1033ecf6836463"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 16:00:21.196205) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 16:00:21.196543) ========= 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=574dc5aafe09851ec43d388c000001b6-1463783280.656629-581723244 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 16:00:21.281401) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 16:00:21.281725) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 16:00:21.282006) ========= ========= Total master_lag: 0.17 =========